[cups] User = lp; Pages= unknown, CUPS 1.7.5

[Haines, Brandan]

Helge, et. al.

I took a look at the debug CUPS log and I think I can at least identify what's going on; the only remaining question is how to "fix" it (if possible).

My user name is BAHAINES and the printer I'm testing with is USAUBIT_RI01. As shown in the 1.6.4 snip, "add_job: requesting-user-name" is done; and I also see a "job-originating-user-name" but I don't see that in 1.7.5.

The other interesting thing I see in 1.7.5 is that it comes back with "Returning HTTP Unauthorized for Create-Job" but then it happily goes on and creates the job anyway (as lp).

Any thoughts are appreciated.

<Cups 1.6.4 Log Snip>
D [20/Nov/2015:12:32:13 +0000] cupsdSetBusyState: Not busy
D [20/Nov/2015:12:32:13 +0000] cupsdReadClient: 11 POST /printers/USAUBIT_RI01 HTTP/1.1
D [20/Nov/2015:12:32:13 +0000] cupsdSetBusyState: Active clients
D [20/Nov/2015:12:32:13 +0000] cupsdAuthorize: No authentication data provided.
D [20/Nov/2015:12:32:13 +0000] cupsdReadClient: 11 1.1 Create-Job 1
D [20/Nov/2015:12:32:13 +0000] Create-Job ipp://localhost/printers/USAUBIT_RI01
D [20/Nov/2015:12:32:13 +0000] cupsdMarkDirty(----J-)
D [20/Nov/2015:12:32:13 +0000] cupsdSetBusyState: Active clients and dirty files
D [20/Nov/2015:12:32:13 +0000] add_job: requesting-user-name="BAHAINES"
I [20/Nov/2015:12:32:13 +0000] Classification="off", ClassifyOverride=0
N [20/Nov/2015:12:32:13 +0000] [Job 28826] CLASSIFICATION FORCED job-sheets="off", job-originating-user-name="BAHAINES"
I [20/Nov/2015:12:32:13 +0000] [Job 28826] Adding start banner page "off".
D [20/Nov/2015:12:32:13 +0000] Discarding unused job-created event...
I [20/Nov/2015:12:32:13 +0000] [Job 28826] Queued on "USAUBIT_RI01" by "BAHAINES".
D [20/Nov/2015:12:32:13 +0000] Returning IPP successful-ok for Create-Job (ipp://localhost/printers/USAUBIT_RI01) from localhost
D [20/Nov/2015:12:32:13 +0000] cupsdSetBusyState: Dirty files
D [20/Nov/2015:12:32:13 +0000] cupsdReadClient: 11 POST /jobs/28826 HTTP/1.1
D [20/Nov/2015:12:32:13 +0000] cupsdSetBusyState: Active clients and dirty files
D [20/Nov/2015:12:32:13 +0000] cupsdAuthorize: No authentication data provided.
D [20/Nov/2015:12:32:13 +0000] cupsdIsAuthorized: username=""
</end 1.6.4 snip>

There's a lot less "stuff" in 1.7.5 log and I don't see my userID at all.

<Cups 1.7.5 Log Snip>
D [19/Nov/2015:21:12:48 +0000] [Client 11] No authentication data provided.
D [19/Nov/2015:21:12:48 +0000] [Client 11] 2.0 Create-Job 2
D [19/Nov/2015:21:12:48 +0000] Create-Job ipp://localhost/printers/USAUBIT_RI01
W [19/Nov/2015:21:12:48 +0000] Unexpected 'document-name' operation attribute in a Create-Job request.
D [19/Nov/2015:21:12:48 +0000] cupsdIsAuthorized: username=""
D [19/Nov/2015:21:12:48 +0000] [Client 11] Returning HTTP Unauthorized for Create-Job (ipp://localhost/printers/USAUBIT_RI01) from localhost
D [19/Nov/2015:21:12:48 +0000] [Client 11] WWW-Authenticate: Basic realm="CUPS"
D [19/Nov/2015:21:12:48 +0000] [Client 11] HTTP_STATE_WAITING Closing on EOF
D [19/Nov/2015:21:12:48 +0000] [Client 11] Closing connection.
D [19/Nov/2015:21:12:48 +0000] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"
...
D [19/Nov/2015:21:12:48 +0000] [Client 11] Authorized as lp using PeerCred
D [19/Nov/2015:21:12:48 +0000] [Client 11] 2.0 Create-Job 2
D [19/Nov/2015:21:12:48 +0000] Create-Job ipp://localhost/printers/USAUBIT_RI01
W [19/Nov/2015:21:12:48 +0000] Unexpected 'document-name' operation attribute in a Create-Job request.
D [19/Nov/2015:21:12:48 +0000] cupsdIsAuthorized: username="lp"
...
I [19/Nov/2015:21:12:48 +0000] [Job 17091467] Queued on "USAUBIT_RI01" by "lp".
D [19/Nov/2015:21:12:48 +0000] [Client 11] Returning IPP successful-ok for Create-Job (ipp://localhost/printers/USAUBIT_RI01) from localhost
D [19/Nov/2015:21:12:48 +0000] [Client 11] Content-Length: 188
</end 1.7.5 Log snip>

-B

-----Original Message-----
From: cups-bounces at cups.org [mailto:cups-bounces at cups.org] On Behalf Of Helge Blischke
Sent: Thursday, November 19, 2015 12:14
To: The CUPS user discussion list. <cups at cups.org>
Subject: Re: [cups] User = lp; Pages= unknown, CUPS 1.7.5

Brandan,

that is, short of the disable flag, the same structure as the other one (plus the explicit suppression of banner pages).
So I see no explanation for why the jobs get "lp“ as user and no pages count.

cups-lpd should write log messages to syslog. Look for messages containing  the string "lpd“.

Helge

This e-mail message is confidential and is intended only for the person(s) named above.  If you have received this message in error, please notify the sender immediately and delete/remove it from your computer system.  Any reading, distribution, printing or disclosure of this message is strictly prohibited if you are not the intended recipient of this message.  Neither this information block, the typed name of the sender, nor anything else in this message is intended to constitute an electronic signature unless a specific statement to the contrary is included in this message.