[cups.general] lpr through printserver fails in 1 out of 30

Mon Mar 28 12:09:38 PDT 2011

Sorry, should have said... CUPS on clients is 1.3.1, cups on 
printserver is 1.4.2, OS on clients is 10.5.8, OS on printserver is 
10.6.7

On 2011-03-28 12:01:52 -0700, Michael Sweet said:

> What version of CUPS are you using?
> 
> What operating system are you using?
> 
> On Mar 28, 2011, at 11:28 AM, Rebecca Simmons wrote:
> 
>> I have 30 identical printers set up with the client machines using lpr
>> to print to them through a central printserver (called printserver).
>> 29 of these machines behave as expected.  The 30th one can be printed
>> to if the managed client is using a bonjour connection to the
>> printserver but not if using an lpr connection to the printserver.
>> With the lpr connection the client gets an instant paused printer, and
>> the job never gets to printserver to be queued.  I've set the cups log
>> to debug 2 on the client and I think the problem is covered by the
>> part below where cupsdDoSelect: polling keeps returning a status 1 (am
>> I right in thinking the "1" here means "failed"?).  The strange thing
>> is that I can print to the selected printer from the client through
>> the printserver with lpr through the command line using
>> 
>> lpr -H printserver.gns -P pr_br_upper /testprint.rtf
>> 
>> Does anyone have an idea how I can fix this? Or even suggestions for
>> further troubleshooting steps?
>> 
>> Thanks
>> 
>> d [28/Mar/2011:10:11:09 -0700] POST /
>> d [28/Mar/2011:10:11:09 -0700] CONTENT_TYPE = application/ipp
>> d [28/Mar/2011:10:11:09 -0700] cupsdReadClient: 20 con-
>>> data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=196, con-
>>> file=-1
>> d [28/Mar/2011:10:11:09 -0700] cupsdProcessIPPRequest(0x7b000[20]):
>> operation_id = 000b
>> D [28/Mar/2011:10:11:09 -0700] Get-Printer-Attributes ipp://localhost/
>> printers/pr_br_upper
>> d [28/Mar/2011:10:11:09 -0700] get_printer_attrs(0x7b000[20], ipp://
>> localhost/printers/pr_br_upper)
>> d [28/Mar/2011:10:11:09 -0700] cupsdFindPolicyOp(p=0x219870, op=b(Get-
>> Printer-Attributes))
>> d [28/Mar/2011:10:11:09 -0700] cupsdFindPolicyOp: Found wildcard
>> match...
>> d [28/Mar/2011:10:11:09 -0700] cupsdIsAuthorized: con->uri="/", con-
>>> best=0x10ee90((null))
>> d [28/Mar/2011:10:11:09 -0700] cupsdIsAuthorized:
>> level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL,
>> num_names=0
>> d [28/Mar/2011:10:11:09 -0700] cupsdIsAuthorized: op=0(unknown-0000)
>> d [28/Mar/2011:10:11:09 -0700] cupsdIsAuthorized:
>> auth=CUPSD_AUTH_ALLOW...
>> d [28/Mar/2011:10:11:09 -0700] copy_attrs(to=0x23c900, from=0x200160,
>> ra=0x23c970, group=0, quickcopy=0)
>> d [28/Mar/2011:10:11:09 -0700] copy_attribute(0x23c900,
>> 0x11d0d0[printer-info,4,41])
>> d [28/Mar/2011:10:11:09 -0700] copy_attrs(to=0x23c900, from=0x110000,
>> ra=0x23c970, group=0, quickcopy=-2147483648)
>> D [28/Mar/2011:10:11:09 -0700] cupsdProcessIPPRequest: 20
>> status_code=0 (successful-ok)
>> d [28/Mar/2011:10:11:09 -0700] cupsdAddSelect: fd=20,
>> read_cb=0x182270f3, write_cb=0x182252b6, data=0x7b000
>> d [28/Mar/2011:10:11:09 -0700] cupsdDoSelect: polling 13 fds for 1
>> seconds...
>> d [28/Mar/2011:10:11:09 -0700] cupsdDoSelect: kevent(3, ...,
>> 16384, ...) returned 1...
>> d [28/Mar/2011:10:11:09 -0700] event->filter=-2, event->ident=20
>> d [28/Mar/2011:10:11:09 -0700] cupsdDoSelect: Write on fd 20...
>> d [28/Mar/2011:10:11:09 -0700] cupsdDoSelect: polling 13 fds for 1
>> seconds...
>> d [28/Mar/2011:10:11:09 -0700] cupsdDoSelect: kevent(3, ...,
>> 16384, ...) returned 1...
>> d [28/Mar/2011:10:11:09 -0700] event->filter=-2, event->ident=20
>> d [28/Mar/2011:10:11:09 -0700] cupsdDoSelect: Write on fd 20...
>> d [28/Mar/2011:10:11:09 -0700] cupsdDoSelect: polling 13 fds for 1
>> seconds...
>> d [28/Mar/2011:10:11:09 -0700] cupsdDoSelect: kevent(3, ...,
>> 16384, ...) returned 1...
>> d [28/Mar/2011:10:11:09 -0700] event->filter=-2, event->ident=20
>> d [28/Mar/2011:10:11:09 -0700] cupsdDoSelect: Write on fd 20...
>> d [28/Mar/2011:10:11:09 -0700] cupsdDoSelect: polling 13 fds for 1
>> seconds...
>> d [28/Mar/2011:10:11:09 -0700] cupsdDoSelect: kevent(3, ...,
>> 16384, ...) returned 1...
>> d [28/Mar/2011:10:11:09 -0700] event->filter=-2, event->ident=20
>> d [28/Mar/2011:10:11:09 -0700] cupsdDoSelect: Write on fd 20...
>> d [28/Mar/2011:10:11:09 -0700] cupsdWriteClient: 20 bytes < 0
>> d [28/Mar/2011:10:11:09 -0700] cupsdAddSelect: fd=20,
>> read_cb=0x182270f3, write_cb=0x0, data=0x7b000
>> d [28/Mar/2011:10:11:09 -0700] cupsdDoSelect: polling 13 fds for 1
>> seconds...
>> d [28/Mar/2011:10:11:09 -0700] cupsdDoSelect: kevent(3, ...,
>> 16384, ...) returned 1...
>> d [28/Mar/2011:10:11:09 -0700] event->filter=-1, event->ident=24
>> d [28/Mar/2011:10:11:09 -0700] cupsdDoSelect: Read on fd 24...
>> D [28/Mar/2011:10:11:09 -0700] [Job 2123] lpd_command returning 1
>> d [28/Mar/2011:10:11:09 -0700] process_children()
>> E [28/Mar/2011:10:11:09 -0700] PID 4162 (/usr/libexec/cups/backend/
>> lpd) stopped with status 1!
>> d [28/Mar/2011:10:11:09 -0700] cupsdDoSelect: polling 13 fds for 1
>> seconds...
>> d [28/Mar/2011:10:11:09 -0700] cupsdDoSelect: kevent(3, ...,
>> 16384, ...) returned 1...
>> d [28/Mar/2011:10:11:09 -0700] event->filter=-1, event->ident=24
>> d [28/Mar/2011:10:11:09 -0700] cupsdDoSelect: Read on fd 24...
>> D [28/Mar/2011:10:11:09 -0700] [Job 2123] File 0 is complete.
>> d [28/Mar/2011:10:11:09 -0700] [Job 2123] cupsdFinishJob: job->status
>> is -256
>> d [28/Mar/2011:10:11:09 -0700] cupsdRemoveSelect: fd=24
>> d [28/Mar/2011:10:11:09 -0700] [Job 2123] cupsdFinishJob: Closing
>> status pipes [ 24 -1 ]...
>> I [28/Mar/2011:10:11:09 -0700] [Job 2123] Backend returned status 1
>> (failed)
>> ____________________________________
>> Rebecca Simmons
>> Technology Services, Glenlyon Norfolk School
>> 
>> rsimmons at mygns.ca	
>> 
>> Glenlyon Norfolk School
>> 801 Bank Street
>> Victoria, British Columbia
>> Canada V8S 4A8
>> 
>> Tel: 1+250-370-6860
>> 
>> _______________________________________________
>> cups mailing list
>> cups at easysw.com
>> http://lists.easysw.com/mailman/listinfo/cups
> 
> ________________________________________________________________________
> Michael Sweet, Senior Printing System Engineer, PWG Chair