D [06/Jul/2011:10:38:27 -0500] PID 16076 (/usr/lib/cups/filter/pstopdf) exited with no errors.
D [06/Jul/2011:10:38:27 -0500] [Job 21] Filetype: PDF
D [06/Jul/2011:10:38:27 -0500] [Job 21] Driver does not understand PDF input, converting to PostScript
D [06/Jul/2011:10:38:27 -0500] [Job 21] Storing temporary files in /var/spool/cups/tmp
D [06/Jul/2011:10:38:27 -0500] PID 16077 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [06/Jul/2011:10:38:27 -0500] [Job 21] Starting process "pdf-to-ps" (generation 1)
D [06/Jul/2011:10:38:27 -0500] [Job 21] Filetype: PostScript
D [06/Jul/2011:10:38:27 -0500] [Job 21] Reading PostScript input ...
D [06/Jul/2011:10:38:27 -0500] [Job 21] --> This document is DSC-conforming!
D [06/Jul/2011:10:38:27 -0500] [Job 21] Job claims to be DSC-conforming, but "%%BeginProlog" was missing before first line with another"%%BeginProlog" comment (is this a TeX/LaTeX/dvips-generated PostScript file?). Assuming start of "Prolog" here.
D [06/Jul/2011:10:38:27 -0500] [Job 21] Inserting option code into "Prolog" section.
D [06/Jul/2011:10:38:27 -0500] [Job 21]
D [06/Jul/2011:10:38:27 -0500] [Job 21] -----------
D [06/Jul/2011:10:38:27 -0500] [Job 21] Found: %%BeginProlog
D [06/Jul/2011:10:38:27 -0500] [Job 21] Found: %%EndProlog
D [06/Jul/2011:10:38:27 -0500] [Job 21]
D [06/Jul/2011:10:38:27 -0500] [Job 21] -----------
D [06/Jul/2011:10:38:27 -0500] [Job 21] Found: %%BeginSetup
D [06/Jul/2011:10:38:27 -0500] [Job 21] Found: %%EndSetup
D [06/Jul/2011:10:38:27 -0500] [Job 21] Inserting PostScript code for CUPS' page accounting
D [06/Jul/2011:10:38:27 -0500] [Job 21] Inserting option code into "Setup" section.
D [06/Jul/2011:10:38:27 -0500] [Job 21]
D [06/Jul/2011:10:38:27 -0500] [Job 21] -----------
D [06/Jul/2011:10:38:27 -0500] [Job 21] New page: %%Page: 1 1
D [06/Jul/2011:10:38:27 -0500] [Job 21]
D [06/Jul/2011:10:38:27 -0500] [Job 21] Found: %%BeginPageSetup
D [06/Jul/2011:10:38:27 -0500] [Job 21] Inserting option code into "PageSetup" section.
D [06/Jul/2011:10:38:27 -0500] [Job 21] New page found but previous not printed, print it now.
D [06/Jul/2011:10:38:27 -0500] [Job 21] Found: %%Page: 2 2
D [06/Jul/2011:10:38:27 -0500] [Job 21] --> Output goes directly to the renderer now.
D [06/Jul/2011:10:38:27 -0500] [Job 21]
D [06/Jul/2011:10:38:27 -0500] [Job 21]
D [06/Jul/2011:10:38:27 -0500] [Job 21] Starting renderer with command: "foo2zjs-wrapper -z1 -P -L0 -r1200x600 -p9 -T3 -m1 -s7 -d1 -n1 "
D [06/Jul/2011:10:38:27 -0500] [Job 21] Starting process "kid3" (generation 1)
D [06/Jul/2011:10:38:27 -0500] [Job 21] Starting process "kid4" (generation 2)
D [06/Jul/2011:10:38:27 -0500] [Job 21] Starting process "renderer" (generation 2)
D [06/Jul/2011:10:38:27 -0500] [Job 21] JCL: 12345X@PJL
D [06/Jul/2011:10:38:27 -0500] [Job 21] <job data>
D [06/Jul/2011:10:38:27 -0500] [Job 21]
D [06/Jul/2011:10:38:27 -0500] [Job 21]
D [06/Jul/2011:10:38:27 -0500] [Job 21] -----------
D [06/Jul/2011:10:38:27 -0500] [Job 21] New page: %%Page: 2 2
D [06/Jul/2011:10:38:27 -0500] [Job 21] Found: %%Page: 2 2
D [06/Jul/2011:10:38:27 -0500] [Job 21] --> Output goes to the FIFO buffer now.
D [06/Jul/2011:10:38:27 -0500] [Job 21]
D [06/Jul/2011:10:38:27 -0500] [Job 21]
D [06/Jul/2011:10:38:27 -0500] [Job 21] Found: %%BeginPageSetup
D [06/Jul/2011:10:38:27 -0500] [Job 21] Inserting option code into "PageSetup" section.
D [06/Jul/2011:10:38:27 -0500] [Job 21] Flushing FIFO.
D [06/Jul/2011:10:38:28 -0500] [Job 21]
D [06/Jul/2011:10:38:28 -0500] [Job 21] Closing renderer
D [06/Jul/2011:10:38:28 -0500] Avahi entry group established for Hewlett-Packard HP LaserJet 1022n @ shanekub
D [06/Jul/2011:10:38:30 -0500] [Job 21] PAGE: 1 1
D [06/Jul/2011:10:38:30 -0500] cupsdMarkDirty(-----S)
D [06/Jul/2011:10:38:30 -0500] [Job 21] STATE: +connecting-to-device
D [06/Jul/2011:10:38:30 -0500] cupsdMarkDirty(-----S)
D [06/Jul/2011:10:38:30 -0500] cupsdAcceptClient: 18 from localhost (Domain)
D [06/Jul/2011:10:38:30 -0500] cupsdReadClient: 18 POST / HTTP/1.1
D [06/Jul/2011:10:38:30 -0500] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Jul/2011:10:38:30 -0500] cupsdAuthorize: No authentication data provided.
D [06/Jul/2011:10:38:30 -0500] cupsdReadClient: 18 1.1 Get-Notifications 1
D [06/Jul/2011:10:38:30 -0500] Get-Notifications /
D [06/Jul/2011:10:38:30 -0500] cupsdIsAuthorized: requesting-user-name="shane"
D [06/Jul/2011:10:38:30 -0500] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [06/Jul/2011:10:38:30 -0500] cupsdSetBusyState: Printing jobs and dirty files
D [06/Jul/2011:10:38:30 -0500] cupsdReadClient: 18 WAITING Closing on EOF
D [06/Jul/2011:10:38:30 -0500] cupsdCloseClient: 18
D [06/Jul/2011:10:38:30 -0500] [Job 21] STATE: -connecting-to-device
D [06/Jul/2011:10:38:30 -0500] [Job 21] STATE: -media-empty-error,media-jam-error,hplip.plugin-error,cover-open-error,toner-empty-error,other
D [06/Jul/2011:10:38:30 -0500] cupsdMarkDirty(-----S)
D [06/Jul/2011:10:38:30 -0500] cupsdAcceptClient: 18 from localhost (Domain)
D [06/Jul/2011:10:38:30 -0500] cupsdReadClient: 18 POST / HTTP/1.1
D [06/Jul/2011:10:38:30 -0500] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Jul/2011:10:38:30 -0500] cupsdAuthorize: No authentication data provided.
D [06/Jul/2011:10:38:30 -0500] cupsdReadClient: 18 1.1 Get-Notifications 1
D [06/Jul/2011:10:38:30 -0500] Get-Notifications /
D [06/Jul/2011:10:38:30 -0500] cupsdIsAuthorized: requesting-user-name="shane"
D [06/Jul/2011:10:38:30 -0500] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [06/Jul/2011:10:38:30 -0500] cupsdSetBusyState: Printing jobs and dirty files
D [06/Jul/2011:10:38:30 -0500] cupsdReadClient: 18 WAITING Closing on EOF
D [06/Jul/2011:10:38:30 -0500] cupsdCloseClient: 18
D [06/Jul/2011:10:38:31 -0500] [Job 21] PAGE: 2 1
D [06/Jul/2011:10:38:31 -0500] cupsdMarkDirty(-----S)
D [06/Jul/2011:10:38:32 -0500] [Job 21] renderer exited with status 0
D [06/Jul/2011:10:38:32 -0500] [Job 21] kid4 exited with status 0
D [06/Jul/2011:10:38:32 -0500] [Job 21] kid3 finished
D [06/Jul/2011:10:38:32 -0500] [Job 21] kid3 exited with status 0
D [06/Jul/2011:10:38:32 -0500] [Job 21] pdf-to-ps exited with status 0
D [06/Jul/2011:10:38:32 -0500] [Job 21]
D [06/Jul/2011:10:38:32 -0500] [Job 21] Closing foomatic-rip.
D [06/Jul/2011:10:38:32 -0500] PID 16078 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
I [06/Jul/2011:10:38:40 -0500] [Job 21] ready to print
D [06/Jul/2011:10:38:40 -0500] cupsdMarkDirty(-----S)
D [06/Jul/2011:10:38:40 -0500] cupsdMarkDirty(-----S)
D [06/Jul/2011:10:38:40 -0500] PID 16079 (/usr/lib/cups/backend/hp) exited with no errors.
D [06/Jul/2011:10:38:40 -0500] cupsdMarkDirty(-----S)
I [06/Jul/2011:10:38:40 -0500] [Job 21] Job completed.
D [06/Jul/2011:10:38:40 -0500] cupsdMarkDirty(----J-)
D [06/Jul/2011:10:38:41 -0500] cupsdAcceptClient: 16 from localhost (Domain)
D [06/Jul/2011:10:38:41 -0500] [Job 21] Unloading...
D [06/Jul/2011:10:38:41 -0500] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Jul/2011:10:38:41 -0500] cupsdSetBusyState: Active clients and dirty files
D [06/Jul/2011:10:38:41 -0500] cupsdAuthorize: No authentication data provided.
D [06/Jul/2011:10:38:41 -0500] cupsdReadClient: 16 1.1 Get-Notifications 1
D [06/Jul/2011:10:38:41 -0500] Get-Notifications /
D [06/Jul/2011:10:38:41 -0500] cupsdIsAuthorized: requesting-user-name="shane"
D [06/Jul/2011:10:38:41 -0500] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [06/Jul/2011:10:38:41 -0500] cupsdSetBusyState: Dirty files
D [06/Jul/2011:10:38:41 -0500] cupsdReadClient: 16 WAITING Closing on EOF
D [06/Jul/2011:10:38:41 -0500] cupsdCloseClient: 16
Same problem printing through the USB setup. After two print jobs it locks up. I reset the printer, and it re-initiated the printing queue. That is the error log above for the relevant problem.
D [06/Jul/ 2011:10: 38:27 -0500] PID 16076 (/usr/lib/ cups/filter/ pstopdf) exited with no errors. 2011:10: 38:27 -0500] [Job 21] Filetype: PDF 2011:10: 38:27 -0500] [Job 21] Driver does not understand PDF input, converting to PostScript 2011:10: 38:27 -0500] [Job 21] Storing temporary files in /var/spool/cups/tmp 2011:10: 38:27 -0500] PID 16077 (/usr/lib/ cups/filter/ pdftopdf) exited with no errors. 2011:10: 38:27 -0500] [Job 21] Starting process "pdf-to-ps" (generation 1) 2011:10: 38:27 -0500] [Job 21] Filetype: PostScript 2011:10: 38:27 -0500] [Job 21] Reading PostScript input ... 2011:10: 38:27 -0500] [Job 21] --> This document is DSC-conforming! 2011:10: 38:27 -0500] [Job 21] Job claims to be DSC-conforming, but "%%BeginProlog" was missing before first line with another" %%BeginProlog" comment (is this a TeX/LaTeX/ dvips-generated PostScript file?). Assuming start of "Prolog" here. 2011:10: 38:27 -0500] [Job 21] Inserting option code into "Prolog" section. 2011:10: 38:27 -0500] [Job 21] 2011:10: 38:27 -0500] [Job 21] ----------- 2011:10: 38:27 -0500] [Job 21] Found: %%BeginProlog 2011:10: 38:27 -0500] [Job 21] Found: %%EndProlog 2011:10: 38:27 -0500] [Job 21] 2011:10: 38:27 -0500] [Job 21] ----------- 2011:10: 38:27 -0500] [Job 21] Found: %%BeginSetup 2011:10: 38:27 -0500] [Job 21] Found: %%EndSetup 2011:10: 38:27 -0500] [Job 21] Inserting PostScript code for CUPS' page accounting 2011:10: 38:27 -0500] [Job 21] Inserting option code into "Setup" section. 2011:10: 38:27 -0500] [Job 21] 2011:10: 38:27 -0500] [Job 21] ----------- 2011:10: 38:27 -0500] [Job 21] New page: %%Page: 1 1 2011:10: 38:27 -0500] [Job 21] 2011:10: 38:27 -0500] [Job 21] Found: %%BeginPageSetup 2011:10: 38:27 -0500] [Job 21] Inserting option code into "PageSetup" section. 2011:10: 38:27 -0500] [Job 21] New page found but previous not printed, print it now. 2011:10: 38:27 -0500] [Job 21] Found: %%Page: 2 2 2011:10: 38:27 -0500] [Job 21] --> Output goes directly to the renderer now. 2011:10: 38:27 -0500] [Job 21] 2011:10: 38:27 -0500] [Job 21] 2011:10: 38:27 -0500] [Job 21] Starting renderer with command: "foo2zjs-wrapper -z1 -P -L0 -r1200x600 -p9 -T3 -m1 -s7 -d1 -n1 " 2011:10: 38:27 -0500] [Job 21] Starting process "kid3" (generation 1) 2011:10: 38:27 -0500] [Job 21] Starting process "kid4" (generation 2) 2011:10: 38:27 -0500] [Job 21] Starting process "renderer" (generation 2) 2011:10: 38:27 -0500] [Job 21] JCL: 12345X@PJL 2011:10: 38:27 -0500] [Job 21] <job data> 2011:10: 38:27 -0500] [Job 21] 2011:10: 38:27 -0500] [Job 21] 2011:10: 38:27 -0500] [Job 21] ----------- 2011:10: 38:27 -0500] [Job 21] New page: %%Page: 2 2 2011:10: 38:27 -0500] [Job 21] Found: %%Page: 2 2 2011:10: 38:27 -0500] [Job 21] --> Output goes to the FIFO buffer now. 2011:10: 38:27 -0500] [Job 21] 2011:10: 38:27 -0500] [Job 21] 2011:10: 38:27 -0500] [Job 21] Found: %%BeginPageSetup 2011:10: 38:27 -0500] [Job 21] Inserting option code into "PageSetup" section. 2011:10: 38:27 -0500] [Job 21] Flushing FIFO. 2011:10: 38:28 -0500] [Job 21] 2011:10: 38:28 -0500] [Job 21] Closing renderer 2011:10: 38:28 -0500] Avahi entry group established for Hewlett-Packard HP LaserJet 1022n @ shanekub 2011:10: 38:30 -0500] [Job 21] PAGE: 1 1 2011:10: 38:30 -0500] cupsdMarkDirty( -----S) 2011:10: 38:30 -0500] [Job 21] STATE: +connecting- to-device 2011:10: 38:30 -0500] cupsdMarkDirty( -----S) 2011:10: 38:30 -0500] cupsdAcceptClient: 18 from localhost (Domain) 2011:10: 38:30 -0500] cupsdReadClient: 18 POST / HTTP/1.1 2011:10: 38:30 -0500] cupsdSetBusyState: Active clients, printing jobs, and dirty files 2011:10: 38:30 -0500] cupsdAuthorize: No authentication data provided. 2011:10: 38:30 -0500] cupsdReadClient: 18 1.1 Get-Notifications 1 2011:10: 38:30 -0500] Get-Notifications / 2011:10: 38:30 -0500] cupsdIsAuthorized: requesting- user-name= "shane" 2011:10: 38:30 -0500] Returning IPP successful-ok for Get-Notifications (/) from localhost 2011:10: 38:30 -0500] cupsdSetBusyState: Printing jobs and dirty files 2011:10: 38:30 -0500] cupsdReadClient: 18 WAITING Closing on EOF 2011:10: 38:30 -0500] cupsdCloseClient: 18 2011:10: 38:30 -0500] [Job 21] STATE: -connecting- to-device 2011:10: 38:30 -0500] [Job 21] STATE: -media- empty-error, media-jam- error,hplip. plugin- error,cover- open-error, toner-empty- error,other 2011:10: 38:30 -0500] cupsdMarkDirty( -----S) 2011:10: 38:30 -0500] cupsdAcceptClient: 18 from localhost (Domain) 2011:10: 38:30 -0500] cupsdReadClient: 18 POST / HTTP/1.1 2011:10: 38:30 -0500] cupsdSetBusyState: Active clients, printing jobs, and dirty files 2011:10: 38:30 -0500] cupsdAuthorize: No authentication data provided. 2011:10: 38:30 -0500] cupsdReadClient: 18 1.1 Get-Notifications 1 2011:10: 38:30 -0500] Get-Notifications / 2011:10: 38:30 -0500] cupsdIsAuthorized: requesting- user-name= "shane" 2011:10: 38:30 -0500] Returning IPP successful-ok for Get-Notifications (/) from localhost 2011:10: 38:30 -0500] cupsdSetBusyState: Printing jobs and dirty files 2011:10: 38:30 -0500] cupsdReadClient: 18 WAITING Closing on EOF 2011:10: 38:30 -0500] cupsdCloseClient: 18 2011:10: 38:31 -0500] [Job 21] PAGE: 2 1 2011:10: 38:31 -0500] cupsdMarkDirty( -----S) 2011:10: 38:32 -0500] [Job 21] renderer exited with status 0 2011:10: 38:32 -0500] [Job 21] kid4 exited with status 0 2011:10: 38:32 -0500] [Job 21] kid3 finished 2011:10: 38:32 -0500] [Job 21] kid3 exited with status 0 2011:10: 38:32 -0500] [Job 21] pdf-to-ps exited with status 0 2011:10: 38:32 -0500] [Job 21] 2011:10: 38:32 -0500] [Job 21] Closing foomatic-rip. 2011:10: 38:32 -0500] PID 16078 (/usr/lib/ cups/filter/ foomatic- rip) exited with no errors. 2011:10: 38:40 -0500] [Job 21] ready to print 2011:10: 38:40 -0500] cupsdMarkDirty( -----S) 2011:10: 38:40 -0500] cupsdMarkDirty( -----S) 2011:10: 38:40 -0500] PID 16079 (/usr/lib/ cups/backend/ hp) exited with no errors. 2011:10: 38:40 -0500] cupsdMarkDirty( -----S) 2011:10: 38:40 -0500] [Job 21] Job completed. 2011:10: 38:40 -0500] cupsdMarkDirty( ----J-) 2011:10: 38:41 -0500] cupsdAcceptClient: 16 from localhost (Domain) 2011:10: 38:41 -0500] [Job 21] Unloading... 2011:10: 38:41 -0500] cupsdReadClient: 16 POST / HTTP/1.1 2011:10: 38:41 -0500] cupsdSetBusyState: Active clients and dirty files 2011:10: 38:41 -0500] cupsdAuthorize: No authentication data provided. 2011:10: 38:41 -0500] cupsdReadClient: 16 1.1 Get-Notifications 1 2011:10: 38:41 -0500] Get-Notifications / 2011:10: 38:41 -0500] cupsdIsAuthorized: requesting- user-name= "shane" 2011:10: 38:41 -0500] Returning IPP successful-ok for Get-Notifications (/) from localhost 2011:10: 38:41 -0500] cupsdSetBusyState: Dirty files 2011:10: 38:41 -0500] cupsdReadClient: 16 WAITING Closing on EOF 2011:10: 38:41 -0500] cupsdCloseClient: 16
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
I [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
I [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
D [06/Jul/
Same problem printing through the USB setup. After two print jobs it locks up. I reset the printer, and it re-initiated the printing queue. That is the error log above for the relevant problem.
Shane