Jobs going to Stopped - Cups Restart the Only Resort

[Anurag]

Dear Cups Team,

We are facing a problem whereby the label printer stops printing when multiple print requests are received in a short span of time.

In my tests, i sent around 80 Labels to print to the printer within a span of 20 seconds. This volume is pretty low for the Warehouse Operations and it is important that the printing works without any problems in such a scenario. Any help in this regard would be highly appreciated.

In my tests, the job number 7357 was the one that got stuck. All other jobs following the job 7357 went to pending status.

After waiting on job 7357, the printer finally decided to Stop the job and the job went to Stopped status. At this stage, the printer status is shown as "/usr/lib/cups/backend/lpd Failed". The only way to print the remaining labels is to restart the printer again.

I have uploaded the complete error_log at http://pastebin.com/2i7V7SZ7

Here are some of the lines from the error_log that are relevant to job 7357, It seems to have failed exactly after 5 minutes of job submission.

I [03/Nov/2010:23:06:58 -0300] [Job 7357] Adding start banner page "none".
D [03/Nov/2010:23:06:58 -0300] Discarding unused job-created event...
I [03/Nov/2010:23:06:58 -0300] [Job 7357] Adding job file of type text/plain.
I [03/Nov/2010:23:06:58 -0300] [Job 7357] Adding end banner page "none".
I [03/Nov/2010:23:06:58 -0300] [Job 7357] Queued on "concepcion_115" by "lgfadm".
D [03/Nov/2010:23:06:58 -0300] [Job 7357] hold_until = 0
D [03/Nov/2010:23:06:58 -0300] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)
D [03/Nov/2010:23:06:58 -0300] cupsdCloseClient: 13
D [03/Nov/2010:23:06:59 -0300] cupsdAcceptClient: skipping getpeercon()
D [03/Nov/2010:23:06:59 -0300] cupsdAcceptClient: 13 from localhost (Domain)
D [03/Nov/2010:23:06:59 -0300] [Job 7352] Unloading...
D [03/Nov/2010:23:06:59 -0300] [Job 7353] Unloading...
D [03/Nov/2010:23:06:59 -0300] [Job 7354] Unloading...
D [03/Nov/2010:23:06:59 -0300] [Job 7355] Unloading...
D [03/Nov/2010:23:06:59 -0300] cupsdReadClient: 13 POST / HTTP/1.1
. 
. 
. 
D [03/Nov/2010:23:07:01 -0300] Discarding unused printer-state-changed event...
D [03/Nov/2010:23:07:01 -0300] Discarding unused printer-state-changed event...
D [03/Nov/2010:23:07:01 -0300] [Job 7357] Connected to 10.8.25.115:515 (IPv4) (local port 994)...
D [03/Nov/2010:23:07:01 -0300] [Job 7357] lpd_command 02 lp-cd-esp-115-P1
D [03/Nov/2010:23:07:01 -0300] [Job 7357] Sending command string (18 bytes)...
D [03/Nov/2010:23:07:01 -0300] [Job 7357] Reading command status...
. 
. 
. 
W [03/Nov/2010:23:12:01 -0300] [Job 7357] Remote host did not respond with command status byte after 300 seconds!
D [03/Nov/2010:23:12:01 -0300] Discarding unused printer-state-changed event...
D [03/Nov/2010:23:12:01 -0300] [Job 7357] lpd_command returning 4
E [03/Nov/2010:23:12:01 -0300] PID 19148 (/usr/lib/cups/backend/lpd) stopped with status 1!
D [03/Nov/2010:23:12:01 -0300] [Job 7357] File 0 is complete.
I [03/Nov/2010:23:12:01 -0300] [Job 7357] Backend returned status 1 (failed)
D [03/Nov/2010:23:12:01 -0300] Discarding unused printer-state-changed event...
D [03/Nov/2010:23:12:01 -0300] Discarding unused printer-stopped event...
I [03/Nov/2010:23:12:01 -0300] Saving printers.conf...
D [03/Nov/2010:23:12:01 -0300] Discarding unused job-stopped event...
D [03/Nov/2010:23:12:02 -0300] [Job 7357] Unloading...
D [03/Nov/2010:23:12:02 -0300] Report: clients=1
D [03/Nov/2010:23:12:02 -0300] Report: jobs=196


The last line in page_log is as follows
	concepcion_115 lgfadm 7357 [03/Nov/2010:23:07:01 -0300] 1 1 - localhost

The printer is using lpd as indicated below and also shown are the printer options
	device for concepcion_115: lpd://10.8.25.115/lp-cd-esp-115-P1

[lgfadm at L573P] /home/lgfadm :\>lpoptions -pconcepcion_115
finishings=3 copies=1 job-hold-until=no-hold job-priority=50 number-up=1 auth-info-required=none job-sheets=none,none printer-info='Concepcion 115' printer-is-accepting-jobs=1 printer-is-shared=1 printer-location=Concepcion printer-make-and-model='Local Raw Printer' printer-state=5 printer-state-change-time=1288836721 printer-state-reasons=paused printer-type=4

Please guide.

Regards,
Anurag