Jobs disappear and not printed

Katya shrek79 at bk.ru
Wed May 16 23:29:37 PDT 2007


> > Helge Blischke wrote:
> >
> > [....]
> > > I don't know the backend named "jasmine",
> > [....]
> >
> > Google found this:
> >
> >    "JASmine is a print accounting system for CUPS. Currently in
> >     developpement stage, it musn’t be considered neither as stable,
> >     nor reliable !"
> >
> > (http://jasmine.berlios.de/dokuwiki/doku.php)
> >
> > Cheers,
> > Kurt
> Thanks for your attention, I changed the backend but jobs are still disappearing (Here is an extract from error log for job Number 402)
> I [14/May/2007:10:37:57 +0400] Adding start banner page "none" to job 402.
> I [14/May/2007:10:37:57 +0400] Adding end banner page "none" to job 402.
> I [14/May/2007:10:37:57 +0400] Job 402 queued on "hp5" by "shrek".
> D [14/May/2007:10:37:57 +0400] Job 402 hold_until = 0
> D [14/May/2007:10:37:57 +0400] [Job 402] argv[0]="hp5"
> D [14/May/2007:10:37:57 +0400] [Job 402] argv[1]="402"
> D [14/May/2007:10:37:57 +0400] [Job 402] argv[2]="shrek"
> D [14/May/2007:10:37:57 +0400] [Job 402] argv[3]="Software  PyKota.com"
> D [14/May/2007:10:37:57 +0400] [Job 402] argv[4]="1"
> D [14/May/2007:10:37:57 +0400] [Job 402] argv[5]="job-uuid=urn:uuid:f2b7f041-e34a-332e-7f0f-c708ea4f45e6"
> D [14/May/2007:10:37:57 +0400] [Job 402] argv[6]="/var/spool/cups/d00402-001"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[1]="CUPS_DATADIR=/usr/share/cups"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[2]="CUPS_DOCROOT=/usr/share/doc/cups-1.2.10"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[6]="CUPS_SERVERROOT=/etc/cups"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[7]="CUPS_STATEDIR=/var/run/cups"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[9]="SERVER_ADMIN=root at qcl4.ihep.su"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[10]="SOFTWARE=CUPS/1.2.10"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[11]="TMPDIR=/var/spool/cups/tmp"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[12]="USER=root"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[13]="CUPS_SERVER=/var/run/cups/cups.sock"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[14]="CUPS_ENCRYPTION=IfRequested"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[15]="IPP_PORT=631"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[16]="CHARSET=utf-8"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[17]="LANG=ru_RU"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[18]="PPD=/etc/cups/ppd/hp5.ppd"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[19]="RIP_MAX_CACHE=8m"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[20]="CONTENT_TYPE=application/postscript"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[21]="DEVICE_URI=socket://172.22.1.5"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[22]="PRINTER=hp5"
> D [14/May/2007:10:37:57 +0400] [Job 402] envp[23]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
> I [14/May/2007:10:37:57 +0400] Started filter /usr/lib/cups/filter/pstops (PID 31161) for job 402.
> I [14/May/2007:10:37:57 +0400] Started backend /usr/lib/cups/backend/socket (PID 31162) for job 402.
> D [14/May/2007:10:37:57 +0400] [Job 402] Connected to 172.22.1.5:9100 (IPv4)...
> D [14/May/2007:10:37:57 +0400] [Job 402] backendRunLoop(print_fd=0, device_fd=4, use_bc=1)
> D [14/May/2007:10:37:57 +0400] [Job 402] Page = 595x842; 12,12 to 583,829
> D [14/May/2007:10:37:57 +0400] [Job 402] slow_collate=0, slow_duplex=0, slow_order=0
> D [14/May/2007:10:37:57 +0400] [Job 402] Before copy_comments - %!PS-Adobe-3.0
> D [14/May/2007:10:37:57 +0400] [Job 402] %!PS-Adobe-3.0
> D [14/May/2007:10:37:57 +0400] [Job 402] %%BoundingBox: 0 0 612 792
> D [14/May/2007:10:37:57 +0400] [Job 402] %%HiResBoundingBox: 0 0 612 792
> D [14/May/2007:10:37:57 +0400] [Job 402] %%Creator: Mozilla PostScript module (rv:1.8.0.7/0)
> D [14/May/2007:10:37:57 +0400] [Job 402] %%DocumentData: Clean8Bit
> D [14/May/2007:10:37:57 +0400] [Job 402] %%DocumentPaperSizes: Letter
> D [14/May/2007:10:37:57 +0400] [Job 402] %%Orientation: Portrait
> D [14/May/2007:10:37:57 +0400] [Job 402] %%Pages: 1
> D [14/May/2007:10:37:57 +0400] [Job 402] %%PageOrder: Ascend
> D [14/May/2007:10:37:57 +0400] [Job 402] %%EndComments
> D [14/May/2007:10:37:57 +0400] [Job 402] Before copy_prolog - % MozillaCharsetName: iso-8859-1
> D [14/May/2007:10:37:57 +0400] [Job 402] Before copy_setup - %%BeginResource: font Nimbus_Sans_L.Bold.0.0.Set0
> D [14/May/2007:10:37:57 +0400] [Job 402] Read 4096 bytes of print data...
> D [14/May/2007:10:37:57 +0400] [Job 402] Wrote 4096 bytes of print data...
> D [14/May/2007:10:37:57 +0400] [Job 402] Read 4096 bytes of print data...
> D [14/May/2007:10:37:57 +0400] [Job 402] Wrote 4096 bytes of print data...
> D [14/May/2007:10:37:57 +0400] [Job 402] Read 4096 bytes of print data...
> D [14/May/2007:10:37:57 +0400] [Job 402] Before page loop - %%Page: 1 1
> D [14/May/2007:10:37:57 +0400] [Job 402] Copying page 1...
> D [14/May/2007:10:37:57 +0400] [Job 402] Wrote 4096 bytes of print data...
> D [14/May/2007:10:37:57 +0400] [Job 402] Read 8192 bytes of print data...
> D [14/May/2007:10:37:57 +0400] [Job 402] pagew = 571.0, pagel = 817.4
> D [14/May/2007:10:37:57 +0400] [Job 402] bboxw = 595, bboxl = 842
> D [14/May/2007:10:37:57 +0400] [Job 402] PageLeft = 12.0, PageRight = 583.0
> D [14/May/2007:10:37:57 +0400] [Job 402] PageTop = 829.4, PageBottom = 12.0
> D [14/May/2007:10:37:57 +0400] [Job 402] PageWidth = 595.0, PageLength = 842.0
> D [14/May/2007:10:37:57 +0400] [Job 402] Wrote 1 pages...
> D [14/May/2007:10:37:57 +0400] [Job 402] Wrote 8192 bytes of print data...
> D [14/May/2007:10:37:57 +0400] [Job 402] Read 8192 bytes of print data...
> D [14/May/2007:10:37:57 +0400] [Job 402] Wrote 8192 bytes of print data...
> D [14/May/2007:10:37:57 +0400] [Job 402] Read 8192 bytes of print data...
> D [14/May/2007:10:37:57 +0400] [Job 402] Wrote 8192 bytes of print data...
> D [14/May/2007:10:37:57 +0400] [Job 402] Read 8192 bytes of print data...
> D [14/May/2007:10:37:57 +0400] [Job 402] Wrote 8192 bytes of print data...
> D [14/May/2007:10:37:57 +0400] [Job 402] Read 8192 bytes of print data...
> D [14/May/2007:10:37:57 +0400] [Job 402] Wrote 8192 bytes of print data...
> D [14/May/2007:10:37:57 +0400] [Job 402] Read 3824 bytes of print data...
> D [14/May/2007:10:37:57 +0400] [Job 402] Wrote 3824 bytes of print data...
> D [14/May/2007:10:37:57 +0400] [Job 402] File 0 is complete.
> D [14/May/2007:10:37:58 +0400] Unloading job 402...
> D [14/May/2007:10:38:03 +0400] Loading attributes for job 402...
> D [14/May/2007:10:42:58 +0400] Unloading job 402...
> D [14/May/2007:10:44:23 +0400] [CGI] Starting "{printer_state=5" at 1402, result=0...
> D [14/May/2007:10:44:23 +0400] [CGI] Starting at file position 1402...
> D [14/May/2007:10:44:23 +0400] [CGI] Starting "{printer_state=5" at 1402, result=0...
> D [14/May/2007:10:44:23 +0400] [CGI] Starting at file position 1402...
> D [14/May/2007:10:45:17 +0400] [CGI] Starting "{printer_state=5" at 1402, result=0...
> D [14/May/2007:10:45:17 +0400] [CGI] Starting at file position 1402...
> D [14/May/2007:10:45:24 +0400] [CGI] Starting "{printer_state=5" at 1402, result=0...
> D [14/May/2007:10:45:24 +0400] [CGI] Starting at file position 1402...
> D [14/May/2007:10:45:24 +0400] [CGI] Starting "{printer_state=5" at 1402, result=0...
> D [14/May/2007:10:45:24 +0400] [CGI] Starting at file position 1402...
> D [14/May/2007:11:06:50 +0400] Loading job 402 from cache...
> D [14/May/2007:11:18:47 +0400] [CGI] Starting "{printer_state=5" at 1402, result=0...
> D [14/May/2007:11:18:47 +0400] [CGI] Starting at file position 1402...
> D [14/May/2007:11:18:47 +0400] [CGI] Starting "{printer_state=5" at 1402, result=0...
> D [14/May/2007:11:18:47 +0400] [CGI] Starting at file position 1402...
> D [14/May/2007:11:22:14 +0400] [CGI] Starting "{printer_state=5" at 1402, result=0...
> D [14/May/2007:11:22:14 +0400] [CGI] Starting at file position 1402...
> D [14/May/2007:11:22:14 +0400] [CGI] Starting "{printer_state=5" at 1402, result=0...
> D [14/May/2007:11:22:14 +0400] [CGI] Starting at file position 1402...
> D [14/May/2007:11:22:25 +0400] Loading attributes for job 402...
> D [14/May/2007:11:23:27 +0400] Unloading job 402...
> D [14/May/2007:11:28:50 +0400] [CGI] Starting "{printer_state=5" at 1402, result=0...
> D [14/May/2007:11:28:50 +0400] [CGI] Starting at file position 1402...
> D [14/May/2007:11:28:50 +0400] [CGI] Starting "{printer_state=5" at 1402, result=0...
> D [14/May/2007:11:28:50 +0400] [CGI] Starting at file position 1402...
> D [14/May/2007:11:29:05 +0400] Loading attributes for job 402...
> D [14/May/2007:11:30:08 +0400] Unloading job 402...
I checked log again and found such a strong record in it:
/var/log/cups/error_log:D [14/May/2007:10:37:57 +0400] Print-Job ipp://localhost
/printers/hp5
before printing job 402.
It is not print-server adress and it's rather strange. Maybe it's the reason why i sometimes lost jobs? What to do with it?




More information about the cups mailing list