Jobs keep status "processing since"

[Robert Boers]

Hello,

I use CUPS as an printserver for our AIX-systems.
The printers I created on CUPS are RAW printers, because I'm printing  only PCL jobs.
I modified the mime.convs files to enable vnd.cups-raw.
Most of the time all jobs are printed without any problems, but sometimes the jobs in a queue are not processing anymore. This can occure one or more printers.
The status of the printer is "green".
There appears an status message saying: "data file sent successfully" besides the name of the printer.
The current job says processing since .....
All other jobs in this queue are pending since .....
Multiple files of the same job are created in /var/spool/cups/:
d00808-1
d00808-2
d00808-..
d00808-10

If I remove this job than all other jobs are processed without errors.
I've tried to use IPP and LPD as URI's.

The AIX servers submits the job 1 time.

I attached the complete debug-logging of error_log regarding the printjobs that does not complete.

The name of my CUPS server is almln01.ads.lan, the job number is 808 and then name of the printer I use is lp_LEEPRN01.

What could be wrong?

Please help.


Kind regards,


Robert Boers
The Netherlands


error_log regarding job 808:


I [15/Mar/2007:12:51:11 +0100] Adding start banner page "none" to job 808.
I [15/Mar/2007:12:51:11 +0100] Job 808 created on "lp_LEEPRN01" by "prsadm".
D [15/Mar/2007:12:51:11 +0100] cupsdReadClient: 8 POST /jobs/808 HTTP/1.1
D [15/Mar/2007:12:51:11 +0100] Send-Document ipp://localhost/jobs/808
I [15/Mar/2007:12:51:11 +0100] File of type application/vnd.cups-raw queued in job #808 by "prsadm".
D [15/Mar/2007:12:51:11 +0100] cupsdReadClient: 8 POST /jobs/808 HTTP/1.1
D [15/Mar/2007:12:51:11 +0100] Send-Document ipp://localhost/jobs/808
I [15/Mar/2007:12:51:11 +0100] File of type application/vnd.cups-raw queued in job #808 by "prsadm".
D [15/Mar/2007:12:51:11 +0100] cupsdReadClient: 8 POST /jobs/808 HTTP/1.1
D [15/Mar/2007:12:51:11 +0100] Send-Document ipp://localhost/jobs/808
I [15/Mar/2007:12:51:11 +0100] File of type application/vnd.cups-raw queued in job #808 by "prsadm".
D [15/Mar/2007:12:51:11 +0100] cupsdReadClient: 8 POST /jobs/808 HTTP/1.1
D [15/Mar/2007:12:51:11 +0100] Send-Document ipp://localhost/jobs/808
I [15/Mar/2007:12:51:11 +0100] File of type application/vnd.cups-raw queued in job #808 by "prsadm".
D [15/Mar/2007:12:51:11 +0100] cupsdReadClient: 8 POST /jobs/808 HTTP/1.1
D [15/Mar/2007:12:51:11 +0100] Send-Document ipp://localhost/jobs/808
I [15/Mar/2007:12:51:11 +0100] File of type application/vnd.cups-raw queued in job #808 by "prsadm".
D [15/Mar/2007:12:51:11 +0100] cupsdReadClient: 8 POST /jobs/808 HTTP/1.1
D [15/Mar/2007:12:51:11 +0100] Send-Document ipp://localhost/jobs/808
I [15/Mar/2007:12:51:11 +0100] File of type application/vnd.cups-raw queued in job #808 by "prsadm".
D [15/Mar/2007:12:51:11 +0100] cupsdReadClient: 8 POST /jobs/808 HTTP/1.1
D [15/Mar/2007:12:51:11 +0100] Send-Document ipp://localhost/jobs/808
I [15/Mar/2007:12:51:11 +0100] File of type application/vnd.cups-raw queued in job #808 by "prsadm".
D [15/Mar/2007:12:51:11 +0100] cupsdReadClient: 8 POST /jobs/808 HTTP/1.1
D [15/Mar/2007:12:51:11 +0100] Send-Document ipp://localhost/jobs/808
I [15/Mar/2007:12:51:11 +0100] File of type application/vnd.cups-raw queued in job #808 by "prsadm".
D [15/Mar/2007:12:51:11 +0100] cupsdReadClient: 8 POST /jobs/808 HTTP/1.1
D [15/Mar/2007:12:51:11 +0100] Send-Document ipp://localhost/jobs/808
I [15/Mar/2007:12:51:11 +0100] File of type application/vnd.cups-raw queued in job #808 by "prsadm".
D [15/Mar/2007:12:51:11 +0100] cupsdReadClient: 8 POST /jobs/808 HTTP/1.1
D [15/Mar/2007:12:51:11 +0100] Send-Document ipp://localhost/jobs/808
I [15/Mar/2007:12:51:11 +0100] File of type application/vnd.cups-raw queued in job #808 by "prsadm".
I [15/Mar/2007:12:51:11 +0100] Adding end banner page "none" to job 808.
D [15/Mar/2007:12:51:11 +0100] [Job 808] Sending job to queue tagged as raw...
D [15/Mar/2007:12:51:11 +0100] [Job 808] argv[0]="lp_LEEPRN01"
D [15/Mar/2007:12:51:11 +0100] [Job 808] argv[1]="808"
D [15/Mar/2007:12:51:11 +0100] [Job 808] argv[2]="prsadm"
D [15/Mar/2007:12:51:11 +0100] [Job 808] argv[3]=""
D [15/Mar/2007:12:51:11 +0100] [Job 808] argv[4]="1"
D [15/Mar/2007:12:51:11 +0100] [Job 808] argv[5]="job-uuid=urn:uuid:0742e60e-5b87-33ad-6366-f0c01d35ce38"
D [15/Mar/2007:12:51:11 +0100] [Job 808] argv[6]="/var/spool/cups/d00808-001"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[2]="CUPS_DOCROOT=/usr/share/doc/cups"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[9]="SERVER_ADMIN=r.boers at asito.com"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[10]="SOFTWARE=CUPS/1.2.8"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[12]="TZ=Europe/Amsterdam"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[13]="USER=root"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[16]="IPP_PORT=631"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[17]="CHARSET=utf-8"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[18]="LANG=en_US"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[19]="PPD=/etc/cups/ppd/lp_LEEPRN01.ppd"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[20]="RIP_MAX_CACHE=8m"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[21]="CONTENT_TYPE=application/vnd.cups-raw"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[22]="DEVICE_URI=lpd://LEEPRN01.ads.lan/lp"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[23]="PRINTER=lp_LEEPRN01"
I [15/Mar/2007:12:51:11 +0100] Started backend /usr/lib/cups/backend/lpd (PID 26185) for job 808.
D [15/Mar/2007:12:51:11 +0100] [Job 808] Connected to 172.20.8.73:515 (IPv4) (local port 1023)...
D [15/Mar/2007:12:51:11 +0100] [Job 808] lpd_command 02 lp
D [15/Mar/2007:12:51:11 +0100] [Job 808] Sending command string (4 bytes)...
D [15/Mar/2007:12:51:11 +0100] [Job 808] Reading command status...
D [15/Mar/2007:12:51:11 +0100] [Job 808] lpd_command returning 0
D [15/Mar/2007:12:51:11 +0100] [Job 808] Control file is:
D [15/Mar/2007:12:51:11 +0100] [Job 808] Halmln01.ads.lan
D [15/Mar/2007:12:51:11 +0100] [Job 808] Pprsadm
D [15/Mar/2007:12:51:11 +0100] [Job 808] J
D [15/Mar/2007:12:51:11 +0100] [Job 808] ldfA185almln01.ads.lan
D [15/Mar/2007:12:51:11 +0100] [Job 808] UdfA185almln01.ads.lan
D [15/Mar/2007:12:51:11 +0100] [Job 808] N
D [15/Mar/2007:12:51:11 +0100] [Job 808] lpd_command 02 75 cfA185almln01.ads.lan
D [15/Mar/2007:12:51:11 +0100] [Job 808] Sending command string (26 bytes)...
D [15/Mar/2007:12:51:11 +0100] [Job 808] Reading command status...
D [15/Mar/2007:12:51:11 +0100] [Job 808] lpd_command returning 0
D [15/Mar/2007:12:51:11 +0100] [Job 808] lpd_command 03 33703 dfA185almln01.ads.lan
D [15/Mar/2007:12:51:11 +0100] [Job 808] Sending command string (29 bytes)...
D [15/Mar/2007:12:51:11 +0100] [Job 808] Reading command status...
D [15/Mar/2007:12:51:11 +0100] [Job 808] lpd_command returning 0
D [15/Mar/2007:12:51:11 +0100] [Job 808] File 0 is complete.
D [15/Mar/2007:12:51:11 +0100] [Job 808] Sending job to queue tagged as raw...
D [15/Mar/2007:12:51:11 +0100] [Job 808] argv[0]="lp_LEEPRN01"
D [15/Mar/2007:12:51:11 +0100] [Job 808] argv[1]="808"
D [15/Mar/2007:12:51:11 +0100] [Job 808] argv[2]="prsadm"
D [15/Mar/2007:12:51:11 +0100] [Job 808] argv[3]=""
D [15/Mar/2007:12:51:11 +0100] [Job 808] argv[4]="1"
D [15/Mar/2007:12:51:11 +0100] [Job 808] argv[5]="job-uuid=urn:uuid:0742e60e-5b87-33ad-6366-f0c01d35ce38"
D [15/Mar/2007:12:51:11 +0100] [Job 808] argv[6]="/var/spool/cups/d00808-002"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[2]="CUPS_DOCROOT=/usr/share/doc/cups"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[9]="SERVER_ADMIN=r.boers at asito.com"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[10]="SOFTWARE=CUPS/1.2.8"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[12]="TZ=Europe/Amsterdam"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[13]="USER=root"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[16]="IPP_PORT=631"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[17]="CHARSET=utf-8"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[18]="LANG=en_US"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[19]="PPD=/etc/cups/ppd/lp_LEEPRN01.ppd"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[20]="RIP_MAX_CACHE=8m"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[21]="CONTENT_TYPE=application/vnd.cups-raw"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[22]="DEVICE_URI=lpd://LEEPRN01.ads.lan/lp"
D [15/Mar/2007:12:51:11 +0100] [Job 808] envp[23]="PRINTER=lp_LEEPRN01"