LinuxQuestions.org

LinuxQuestions.org (/questions/)
-   Linux - Hardware (http://www.linuxquestions.org/questions/linux-hardware-18/)
-   -   HP DJ 4160 won't print. cups gives errors in error log (http://www.linuxquestions.org/questions/linux-hardware-18/hp-dj-4160-wont-print-cups-gives-errors-in-error-log-510954/)

jonlake 12-16-2006 12:29 AM

HP DJ 4160 won't print. cups gives errors in error log
 
I have added a printer via cups (This is working on my ubuntu system, but not on my slackware 11.0 system). It gives the following error in the error_log when attempting to print a test page. The log level is set to debug. I have the slackware hpijs package installed along with esp-gs-8.15 and the gs fonts package.


Code:

D [16/Dec/2006:01:26:12 -0500] AcceptClient: 5 from localhost:631.
D [16/Dec/2006:01:26:12 -0500] ReadClient: 5 GET /printers HTTP/1.1
D [16/Dec/2006:01:26:12 -0500] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 21350
I [16/Dec/2006:01:26:12 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=21350)
D [16/Dec/2006:01:26:12 -0500] SendCommand: 5 file=7
D [16/Dec/2006:01:26:12 -0500] AcceptClient: 8 from localhost:631.
D [16/Dec/2006:01:26:12 -0500] ReadClient: 8 POST / HTTP/1.1
D [16/Dec/2006:01:26:12 -0500] ProcessIPPRequest: 8 status_code=1
D [16/Dec/2006:01:26:12 -0500] ReadClient: 8 POST / HTTP/1.1
D [16/Dec/2006:01:26:12 -0500] ProcessIPPRequest: 8 status_code=1
D [16/Dec/2006:01:26:12 -0500] CloseClient: 8
D [16/Dec/2006:01:26:19 -0500] ReadClient: 5 GET /printers/4160?op=print-test-page HTTP/1.1
D [16/Dec/2006:01:26:19 -0500] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 21351
I [16/Dec/2006:01:26:19 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=21351)
D [16/Dec/2006:01:26:19 -0500] SendCommand: 5 file=8
D [16/Dec/2006:01:26:19 -0500] AcceptClient: 7 from localhost:631.
D [16/Dec/2006:01:26:19 -0500] ReadClient: 7 POST /printers/4160 HTTP/1.1
D [16/Dec/2006:01:26:19 -0500] print_job: request file type is application/postscript.
D [16/Dec/2006:01:26:19 -0500] check_quotas: requesting-user-name = 'root'
D [16/Dec/2006:01:26:19 -0500] print_job: requesting-user-name = 'root'
D [16/Dec/2006:01:26:19 -0500] Adding default job-sheets values "none,none"...
I [16/Dec/2006:01:26:19 -0500] Adding start banner page "none" to job 6.
I [16/Dec/2006:01:26:19 -0500] Adding end banner page "none" to job 6.
I [16/Dec/2006:01:26:19 -0500] Job 6 queued on '4160' by 'root'.
D [16/Dec/2006:01:26:19 -0500] Job 6 hold_until = 0
D [16/Dec/2006:01:26:19 -0500] StartJob(6, 0x80e0b70)
D [16/Dec/2006:01:26:19 -0500] StartJob() id = 6, file = 0/1
D [16/Dec/2006:01:26:19 -0500] job-sheets=none,none
D [16/Dec/2006:01:26:19 -0500] banner_page = 0
D [16/Dec/2006:01:26:19 -0500] StartJob: argv = "4160","6","root","Test Page","1","","/var/spool/cups/d00006-001"
D [16/Dec/2006:01:26:19 -0500] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [16/Dec/2006:01:26:19 -0500] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [16/Dec/2006:01:26:19 -0500] StartJob: envp[2]="USER=root"
D [16/Dec/2006:01:26:19 -0500] StartJob: envp[3]="CHARSET=utf-8"
D [16/Dec/2006:01:26:19 -0500] StartJob: envp[4]="LANG=en_US"
D [16/Dec/2006:01:26:19 -0500] StartJob: envp[5]="PPD=/etc/cups/ppd/4160.ppd"
D [16/Dec/2006:01:26:19 -0500] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [16/Dec/2006:01:26:19 -0500] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [16/Dec/2006:01:26:19 -0500] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [16/Dec/2006:01:26:19 -0500] StartJob: envp[9]="CONTENT_TYPE=application/postscript"
D [16/Dec/2006:01:26:19 -0500] StartJob: envp[10]="DEVICE_URI=usb://C9068A?serial=TH65Q824T204D6"
D [16/Dec/2006:01:26:19 -0500] StartJob: envp[11]="PRINTER=4160"
D [16/Dec/2006:01:26:19 -0500] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
D [16/Dec/2006:01:26:19 -0500] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [16/Dec/2006:01:26:19 -0500] StartJob: envp[14]="CUPS_SERVER=localhost"
D [16/Dec/2006:01:26:19 -0500] StartJob: envp[15]="IPP_PORT=631"
D [16/Dec/2006:01:26:19 -0500] StartJob: statusfds = [ 9 10 ]
D [16/Dec/2006:01:26:19 -0500] StartJob: filterfds[1] = [ 11 -1 ]
D [16/Dec/2006:01:26:19 -0500] StartJob: filter = "/usr/lib/cups/filter/pstops"
D [16/Dec/2006:01:26:19 -0500] StartJob: filterfds[0] = [ 12 13 ]
D [16/Dec/2006:01:26:19 -0500] start_process("/usr/lib/cups/filter/pstops", 0xbffee320, 0xbffed690, 11, 13, 10)
I [16/Dec/2006:01:26:19 -0500] Started filter /usr/lib/cups/filter/pstops (PID 21352) for job 6.
D [16/Dec/2006:01:26:19 -0500] StartJob: filter = "/usr/lib/cups/filter/foomatic-rip"
D [16/Dec/2006:01:26:19 -0500] StartJob: filterfds[1] = [ 11 14 ]
D [16/Dec/2006:01:26:19 -0500] start_process("/usr/lib/cups/filter/foomatic-rip", 0xbffee320, 0xbffed690, 12, 14, 10)
I [16/Dec/2006:01:26:19 -0500] Started filter /usr/lib/cups/filter/foomatic-rip (PID 21353) for job 6.
D [16/Dec/2006:01:26:19 -0500] StartJob: backend = "/usr/lib/cups/backend/usb"
D [16/Dec/2006:01:26:19 -0500] StartJob: filterfds[0] = [ -1 12 ]
D [16/Dec/2006:01:26:19 -0500] start_process("/usr/lib/cups/backend/usb", 0xbffee320, 0xbffed690, 11, 12, 10)
I [16/Dec/2006:01:26:19 -0500] Started backend /usr/lib/cups/backend/usb (PID 21354) for job 6.
D [16/Dec/2006:01:26:19 -0500] ProcessIPPRequest: 7 status_code=0
D [16/Dec/2006:01:26:19 -0500] [Job 6] Page = 595x842; 10,36 to 585,833
D [16/Dec/2006:01:26:19 -0500] [Job 6] slowcollate=0, slowduplex=0, sloworder=0
D [16/Dec/2006:01:26:19 -0500] [Job 6] 0 %%BoundingBox: 0 0 612 792
D [16/Dec/2006:01:26:19 -0500] [Job 6] 0 %%Pages: 1
D [16/Dec/2006:01:26:19 -0500] [Job 6] 0 %%LanguageLevel: 1
D [16/Dec/2006:01:26:19 -0500] [Job 6] 0 %%DocumentData: Clean7Bit
D [16/Dec/2006:01:26:19 -0500] [Job 6] 0 %%DocumentSuppliedResources: procset testprint/1.1
D [16/Dec/2006:01:26:19 -0500] [Job 6] 0 %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman
D [16/Dec/2006:01:26:19 -0500] [Job 6] 0 %%Creator: Michael Sweet, Easy Software Products
D [16/Dec/2006:01:26:19 -0500] [Job 6] 0 %%CreationDate: May 11, 1999
D [16/Dec/2006:01:26:19 -0500] [Job 6] 0 %%Title: Test Page
D [16/Dec/2006:01:26:19 -0500] [Job 6] 0 %%EndComments
D [16/Dec/2006:01:26:19 -0500] [Job 6] 0 %%BeginProlog
D [16/Dec/2006:01:26:19 -0500] [Job 6] 0 %%BeginResource procset testprint 1.1 0
D [16/Dec/2006:01:26:19 -0500] [Job 6] 0 %%EndResource
D [16/Dec/2006:01:26:19 -0500] [Job 6] 0 %%EndProlog
D [16/Dec/2006:01:26:19 -0500] [Job 6] 0 %%Page: 1 1
D [16/Dec/2006:01:26:19 -0500] [Job 6] 0 %%Page: 1 1
D [16/Dec/2006:01:26:19 -0500] [Job 6] pw = 575.6, pl = 797.0
D [16/Dec/2006:01:26:19 -0500] [Job 6] PageLeft = 9.7, PageRight = 585.3
D [16/Dec/2006:01:26:19 -0500] [Job 6] PageTop = 833.0, PageBottom = 36.0
D [16/Dec/2006:01:26:19 -0500] [Job 6] PageWidth = 595.0, PageLength = 842.0
D [16/Dec/2006:01:26:19 -0500] CloseClient: 7
D [16/Dec/2006:01:26:19 -0500] [Job 6] Printer using device file "/dev/usb/lp0"...
D [16/Dec/2006:01:26:19 -0500] [Job 6] LPGETSTATUS returned a port status of 18...
D [16/Dec/2006:01:26:20 -0500] [Job 6] foomatic-rip version $Revision: 3.43.2.12 $ running...
D [16/Dec/2006:01:26:20 -0500] [Job 6] Parsing PPD file ...
D [16/Dec/2006:01:26:20 -0500] [Job 6] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"
D [16/Dec/2006:01:26:20 -0500] [Job 6] Added option ColorSpace
D [16/Dec/2006:01:26:20 -0500] [Job 6] Added option Resolution
D [16/Dec/2006:01:26:20 -0500] [Job 6] Added option PageSize
D [16/Dec/2006:01:26:20 -0500] [Job 6] Added option PageRegion
D [16/Dec/2006:01:26:20 -0500] [Job 6] Added option Model
D [16/Dec/2006:01:26:20 -0500] [Job 6] Added option PrintoutMode
D [16/Dec/2006:01:26:20 -0500] [Job 6] Added option ImageableArea
D [16/Dec/2006:01:26:20 -0500] [Job 6] Added option PaperDimension
D [16/Dec/2006:01:26:20 -0500] [Job 6] Added option Quality
D [16/Dec/2006:01:26:20 -0500] [Job 6] Added option Font
D [16/Dec/2006:01:26:20 -0500] [Job 6]
D [16/Dec/2006:01:26:20 -0500] [Job 6] Parameter Summary
D [16/Dec/2006:01:26:20 -0500] [Job 6] -----------------
D [16/Dec/2006:01:26:20 -0500] [Job 6]
D [16/Dec/2006:01:26:20 -0500] [Job 6] Spooler: cups
D [16/Dec/2006:01:26:20 -0500] [Job 6] Printer: 4160
D [16/Dec/2006:01:26:20 -0500] [Job 6] PPD file: /etc/cups/ppd/4160.ppd
D [16/Dec/2006:01:26:20 -0500] [Job 6] Printer model: HP DeskJet D4100 Foomatic/hpijs (recommended)
D [16/Dec/2006:01:26:20 -0500] [Job 6] Job title: Test Page
D [16/Dec/2006:01:26:20 -0500] [Job 6] File(s) to be printed:
D [16/Dec/2006:01:26:20 -0500] [Job 6] <STDIN>
D [16/Dec/2006:01:26:20 -0500] [Job 6]
D [16/Dec/2006:01:26:20 -0500] [Job 6] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [16/Dec/2006:01:26:20 -0500] [Job 6]
D [16/Dec/2006:01:26:20 -0500] [Job 6] ================================================
D [16/Dec/2006:01:26:20 -0500] [Job 6]
D [16/Dec/2006:01:26:20 -0500] [Job 6] File: <STDIN>
D [16/Dec/2006:01:26:20 -0500] [Job 6]
D [16/Dec/2006:01:26:20 -0500] [Job 6] ================================================
D [16/Dec/2006:01:26:20 -0500] [Job 6]
D [16/Dec/2006:01:26:20 -0500] [Job 6] Reading PostScript input ...
D [16/Dec/2006:01:26:20 -0500] [Job 6] --> This document is DSC-conforming!
D [16/Dec/2006:01:26:20 -0500] [Job 6]
D [16/Dec/2006:01:26:20 -0500] [Job 6] -----------
D [16/Dec/2006:01:26:20 -0500] [Job 6] Found: %%BeginProlog
D [16/Dec/2006:01:26:20 -0500] [Job 6] 0 %%EOF
D [16/Dec/2006:01:26:20 -0500] [Job 6] Saw EOF!
D [16/Dec/2006:01:26:20 -0500] [Job 6] Found: %%EndProlog
D [16/Dec/2006:01:26:20 -0500] [Job 6]
D [16/Dec/2006:01:26:20 -0500] [Job 6] -----------
D [16/Dec/2006:01:26:20 -0500] [Job 6] Found: %%BeginSetup
D [16/Dec/2006:01:26:20 -0500] [Job 6] Found: %%BeginFeature: *PrintoutMode Normal
D [16/Dec/2006:01:26:20 -0500] [Job 6] Option: PrintoutMode=Normal --> Setting option
D [16/Dec/2006:01:26:20 -0500] [Job 6] Found: %% FoomaticRIPOptionSetting: PrintoutMode=Normal
D [16/Dec/2006:01:26:20 -0500] [Job 6] Option: PrintoutMode=Normal --> Setting option
D [16/Dec/2006:01:26:20 -0500] [Job 6] Found: %%BeginFeature: *Quality FromPrintoutMode
D [16/Dec/2006:01:26:20 -0500] [Job 6] Option: Quality=FromPrintoutMode --> Setting option
D [16/Dec/2006:01:26:20 -0500] [Job 6] Found: %% FoomaticRIPOptionSetting: Quality=@PrintoutMode
D [16/Dec/2006:01:26:20 -0500] [Job 6] Option: Quality=FromPrintoutMode --> Setting option
D [16/Dec/2006:01:26:20 -0500] [Job 6] Found: %%BeginFeature: *PageSize A4
D [16/Dec/2006:01:26:20 -0500] [Job 6] Option: PageSize=A4 --> Setting option
D [16/Dec/2006:01:26:20 -0500] [Job 6] Found: %% FoomaticRIPOptionSetting: PageSize=A4
D [16/Dec/2006:01:26:20 -0500] [Job 6] Option: PageSize=A4 --> Setting option
D [16/Dec/2006:01:26:20 -0500] [Job 6] Found: %%EndSetup
D [16/Dec/2006:01:26:20 -0500] [Job 6] Inserting PostScript code for CUPS' page accounting
D [16/Dec/2006:01:26:20 -0500] [Job 6]
D [16/Dec/2006:01:26:20 -0500] [Job 6] -----------
D [16/Dec/2006:01:26:20 -0500] [Job 6] New page:  1 1
D [16/Dec/2006:01:26:20 -0500] [Job 6] Inserting option code into "PageSetup" section.
D [16/Dec/2006:01:26:20 -0500] [Job 6] No page header or page header not DSC-conforming
D [16/Dec/2006:01:26:20 -0500] [Job 6] Stopping search for page header options
D [16/Dec/2006:01:26:20 -0500] [Job 6] Found:
D [16/Dec/2006:01:26:20 -0500] [Job 6] pageHeight sub                % Move down...
D [16/Dec/2006:01:26:20 -0500] [Job 6] --> Output goes directly to the renderer now.
D [16/Dec/2006:01:26:20 -0500] [Job 6]
D [16/Dec/2006:01:26:20 -0500] [Job 6]
D [16/Dec/2006:01:26:20 -0500] [Job 6] Starting renderer
D [16/Dec/2006:01:26:20 -0500] [Job 6] renderer PID kid4=21356
D [16/Dec/2006:01:26:21 -0500] [Job 6] JCL: <job data>
D [16/Dec/2006:01:26:21 -0500] [Job 6]
D [16/Dec/2006:01:26:21 -0500] [Job 6] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="Deskjet D4100" -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -r600 -sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=1 -dIjsUseOutputFD -sOutputFile=- -
D [16/Dec/2006:01:26:21 -0500] [Job 6] foomatic-gswrapper: gs '-sstdout=%stderr' '-dBATCH' '-dPARANOIDSAFER' '-dQUIET' '-dNOPAUSE' '-sDEVICE=ijs' '-sIjsServer=hpijs' '-sDeviceManufacturer=HEWLETT-PACKARD' '-sDeviceModel=Deskjet D4100' '-dDEVICEWIDTHPOINTS=595' '-dDEVICEHEIGHTPOINTS=842' '-r600' '-sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=1' '-dIjsUseOutputFD' '-sOutputFile=%stdout' '-'
D [16/Dec/2006:01:26:21 -0500] [Job 6] unable to set device=Deskjet D4100, err=16
D [16/Dec/2006:01:26:21 -0500] [Job 6] **** Unable to open the initial device, quitting.
D [16/Dec/2006:01:26:21 -0500] [Job 6] renderer return value: 1
D [16/Dec/2006:01:26:21 -0500] [Job 6] renderer received signal: 1
D [16/Dec/2006:01:26:21 -0500] [Job 6] Process dying with "Possible error on renderer command line or PostScript error. Check options.", exit stat: 3
D [16/Dec/2006:01:26:21 -0500] [Job 6] error: Illegal seek (29)
D [16/Dec/2006:01:26:21 -0500] [Job 6] Possible error on renderer command line or PostScript error. Check options.
D [16/Dec/2006:01:26:21 -0500] [Job 6]
D [16/Dec/2006:01:26:21 -0500] [Job 6] Closing renderer
D [16/Dec/2006:01:26:21 -0500] [Job 6] KID3 exited with status 3
D [16/Dec/2006:01:26:21 -0500] [Job 6] Renderer exit stat: 3
D [16/Dec/2006:01:26:21 -0500] [Job 6] Renderer process finished
D [16/Dec/2006:01:26:21 -0500] [Job 6] Killing process 21355 (KID3)
D [16/Dec/2006:01:26:21 -0500] [Job 6] Process dying with "Error closing renderer", exit stat: 3
D [16/Dec/2006:01:26:21 -0500] [Job 6] error: Bad file descriptor (9)
D [16/Dec/2006:01:26:21 -0500] [Job 6] Error closing renderer
E [16/Dec/2006:01:26:21 -0500] PID 21353 stopped with status 3!
D [16/Dec/2006:01:26:21 -0500] [Job 6] unable to read client data err=-2
D [16/Dec/2006:01:26:21 -0500] [Job 6] tail process done writing data to STDOUT
D [16/Dec/2006:01:26:21 -0500] [Job 6] KID4 finished
D [16/Dec/2006:01:26:21 -0500] UpdateJob: job 6, file 0 is complete.
D [16/Dec/2006:01:26:21 -0500] CancelJob: id = 6
D [16/Dec/2006:01:26:21 -0500] StopJob: id = 6, force = 0
D [16/Dec/2006:01:26:21 -0500] StopJob: printer state is 3


jonlake 12-16-2006 10:46 PM

Update
 
Ok, I have been doing a little playing around with this. I have my printer hooked up to my ubuntu system that will print to this printer and have published the printer. Now, when I log into cups on the slackware system, it shows the printer available. I can print to it, kind of. When I send a job to it, the lights on the printer start flashing really fast. Then, if I hit the power button, the printer doesn't turn off, but the lights stop flashing really fast and the job prints. There are a few small marks on the top of the paper, but not a big deal unless I'm printing a resume or something. I have compiled (with ijs) and installed the latest esp-ghostscript software.
uo machine. When I send a job to it, the cups web interface for the printer states ""Print file was not accepted (client-error-bad-request)!" ". Some reading finds that this error can mean many different things. However, the slackware client still seems to have a similar error as when it was connected directly via usb. It is "unable to set device=Deskjet D4100, err=16".

Code:

D [16/Dec/2006:23:42:11 -0500] AcceptClient: 5 from localhost:631.
D [16/Dec/2006:23:42:11 -0500] ReadClient: 5 GET /printers/HP_DJ_4160_IPP?op=print-test-page HTTP/1.1
D [16/Dec/2006:23:42:11 -0500] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 17254
I [16/Dec/2006:23:42:11 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=17254)
D [16/Dec/2006:23:42:11 -0500] SendCommand: 5 file=8
D [16/Dec/2006:23:42:11 -0500] AcceptClient: 7 from localhost:631.
D [16/Dec/2006:23:42:11 -0500] ReadClient: 7 POST /printers/HP_DJ_4160_IPP HTTP/1.1
D [16/Dec/2006:23:42:11 -0500] print_job: request file type is application/postscript.
D [16/Dec/2006:23:42:11 -0500] check_quotas: requesting-user-name = 'root'
D [16/Dec/2006:23:42:11 -0500] print_job: requesting-user-name = 'root'
D [16/Dec/2006:23:42:11 -0500] Adding default job-sheets values "none,none"...
I [16/Dec/2006:23:42:11 -0500] Adding start banner page "none" to job 38.
I [16/Dec/2006:23:42:11 -0500] Adding end banner page "none" to job 38.
I [16/Dec/2006:23:42:11 -0500] Job 38 queued on 'HP_DJ_4160_IPP' by 'root'.
D [16/Dec/2006:23:42:11 -0500] Job 38 hold_until = 0
D [16/Dec/2006:23:42:11 -0500] StartJob(38, 0x8202858)
D [16/Dec/2006:23:42:11 -0500] StartJob() id = 38, file = 0/1
D [16/Dec/2006:23:42:11 -0500] job-sheets=none,none
D [16/Dec/2006:23:42:11 -0500] banner_page = 0
D [16/Dec/2006:23:42:11 -0500] StartJob: argv = "HP_DJ_4160_IPP","38","root","Test Page","1","","/var/spool/cups/d00038-001"
D [16/Dec/2006:23:42:11 -0500] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [16/Dec/2006:23:42:11 -0500] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [16/Dec/2006:23:42:11 -0500] StartJob: envp[2]="USER=root"
D [16/Dec/2006:23:42:11 -0500] StartJob: envp[3]="CHARSET=utf-8"
D [16/Dec/2006:23:42:11 -0500] StartJob: envp[4]="LANG=en_US"
D [16/Dec/2006:23:42:11 -0500] StartJob: envp[5]="PPD=/etc/cups/ppd/HP_DJ_4160_IPP.ppd"
D [16/Dec/2006:23:42:11 -0500] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [16/Dec/2006:23:42:11 -0500] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [16/Dec/2006:23:42:11 -0500] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [16/Dec/2006:23:42:11 -0500] StartJob: envp[9]="CONTENT_TYPE=application/postscript"
D [16/Dec/2006:23:42:11 -0500] StartJob: envp[10]="DEVICE_URI=http://192.168.2.22:631/printers/hp4160"
D [16/Dec/2006:23:42:11 -0500] StartJob: envp[11]="PRINTER=HP_DJ_4160_IPP"
D [16/Dec/2006:23:42:11 -0500] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
D [16/Dec/2006:23:42:11 -0500] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [16/Dec/2006:23:42:11 -0500] StartJob: envp[14]="CUPS_SERVER=localhost"
D [16/Dec/2006:23:42:11 -0500] StartJob: envp[15]="IPP_PORT=631"
D [16/Dec/2006:23:42:11 -0500] StartJob: statusfds = [ 9 10 ]
D [16/Dec/2006:23:42:11 -0500] StartJob: filterfds[1] = [ 11 -1 ]
D [16/Dec/2006:23:42:11 -0500] StartJob: filter = "/usr/lib/cups/filter/pstops"
D [16/Dec/2006:23:42:11 -0500] StartJob: filterfds[0] = [ 12 13 ]
D [16/Dec/2006:23:42:11 -0500] start_process("/usr/lib/cups/filter/pstops", 0xbffee320, 0xbffed690, 11, 13, 10)
I [16/Dec/2006:23:42:11 -0500] Started filter /usr/lib/cups/filter/pstops (PID 17255) for job 38.
D [16/Dec/2006:23:42:11 -0500] StartJob: filter = "/usr/lib/cups/filter/foomatic-rip"
D [16/Dec/2006:23:42:11 -0500] StartJob: filterfds[1] = [ 11 14 ]
D [16/Dec/2006:23:42:11 -0500] start_process("/usr/lib/cups/filter/foomatic-rip", 0xbffee320, 0xbffed690, 12, 14, 10)
I [16/Dec/2006:23:42:11 -0500] Started filter /usr/lib/cups/filter/foomatic-rip (PID 17256) for job 38.
D [16/Dec/2006:23:42:11 -0500] StartJob: backend = "/usr/lib/cups/backend/http"
D [16/Dec/2006:23:42:11 -0500] StartJob: filterfds[0] = [ -1 12 ]
D [16/Dec/2006:23:42:11 -0500] start_process("/usr/lib/cups/backend/http", 0xbffee320, 0xbffed690, 11, 12, 10)
I [16/Dec/2006:23:42:11 -0500] Started backend /usr/lib/cups/backend/http (PID 17257) for job 38.
D [16/Dec/2006:23:42:11 -0500] ProcessIPPRequest: 7 status_code=0
D [16/Dec/2006:23:42:11 -0500] CloseClient: 7
D [16/Dec/2006:23:42:11 -0500] [Job 38] Page = 595x842; 10,36 to 585,833
D [16/Dec/2006:23:42:11 -0500] [Job 38] slowcollate=0, slowduplex=0, sloworder=0
D [16/Dec/2006:23:42:11 -0500] [Job 38] 0 %%BoundingBox: 0 0 612 792
D [16/Dec/2006:23:42:11 -0500] [Job 38] 0 %%Pages: 1
D [16/Dec/2006:23:42:11 -0500] [Job 38] 0 %%LanguageLevel: 1
D [16/Dec/2006:23:42:11 -0500] [Job 38] 0 %%DocumentData: Clean7Bit
D [16/Dec/2006:23:42:11 -0500] [Job 38] 0 %%DocumentSuppliedResources: procset testprint/1.1
D [16/Dec/2006:23:42:11 -0500] [Job 38] 0 %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman
D [16/Dec/2006:23:42:11 -0500] [Job 38] 0 %%Creator: Michael Sweet, Easy Software Products
D [16/Dec/2006:23:42:11 -0500] [Job 38] 0 %%CreationDate: May 11, 1999
D [16/Dec/2006:23:42:11 -0500] [Job 38] 0 %%Title: Test Page
D [16/Dec/2006:23:42:11 -0500] [Job 38] 0 %%EndComments
D [16/Dec/2006:23:42:11 -0500] [Job 38] 0 %%BeginProlog
D [16/Dec/2006:23:42:11 -0500] [Job 38] 0 %%BeginResource procset testprint 1.1 0
D [16/Dec/2006:23:42:11 -0500] [Job 38] 0 %%EndResource
D [16/Dec/2006:23:42:11 -0500] [Job 38] 0 %%EndProlog
D [16/Dec/2006:23:42:11 -0500] [Job 38] 0 %%Page: 1 1
D [16/Dec/2006:23:42:11 -0500] [Job 38] 0 %%Page: 1 1
D [16/Dec/2006:23:42:11 -0500] [Job 38] pw = 575.6, pl = 797.0
D [16/Dec/2006:23:42:11 -0500] [Job 38] PageLeft = 9.7, PageRight = 585.3
D [16/Dec/2006:23:42:11 -0500] [Job 38] PageTop = 833.0, PageBottom = 36.0
D [16/Dec/2006:23:42:11 -0500] [Job 38] PageWidth = 595.0, PageLength = 842.0
D [16/Dec/2006:23:42:13 -0500] [Job 38] foomatic-rip version $Revision: 3.43.2.12 $ running...
D [16/Dec/2006:23:42:13 -0500] [Job 38] Parsing PPD file ...
D [16/Dec/2006:23:42:13 -0500] [Job 38] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"
D [16/Dec/2006:23:42:13 -0500] [Job 38] Added option ColorSpace
D [16/Dec/2006:23:42:13 -0500] [Job 38] Added option Resolution
D [16/Dec/2006:23:42:13 -0500] [Job 38] Added option PageSize
D [16/Dec/2006:23:42:13 -0500] [Job 38] Added option PageRegion
D [16/Dec/2006:23:42:13 -0500] [Job 38] Added option Model
D [16/Dec/2006:23:42:13 -0500] [Job 38] Added option PrintoutMode
D [16/Dec/2006:23:42:13 -0500] [Job 38] Added option ImageableArea
D [16/Dec/2006:23:42:13 -0500] [Job 38] Added option PaperDimension
D [16/Dec/2006:23:42:13 -0500] [Job 38] Added option Quality
D [16/Dec/2006:23:42:13 -0500] [Job 38] Added option Font
D [16/Dec/2006:23:42:13 -0500] [Job 38]
D [16/Dec/2006:23:42:13 -0500] [Job 38] Parameter Summary
D [16/Dec/2006:23:42:13 -0500] [Job 38] -----------------
D [16/Dec/2006:23:42:13 -0500] [Job 38]
D [16/Dec/2006:23:42:13 -0500] [Job 38] Spooler: cups
D [16/Dec/2006:23:42:13 -0500] [Job 38] Printer: HP_DJ_4160_IPP
D [16/Dec/2006:23:42:13 -0500] [Job 38] PPD file: /etc/cups/ppd/HP_DJ_4160_IPP.ppd
D [16/Dec/2006:23:42:13 -0500] [Job 38] Printer model: HP DeskJet D4100 Foomatic/hpijs (recommended)
D [16/Dec/2006:23:42:13 -0500] [Job 38] Job title: Test Page
D [16/Dec/2006:23:42:13 -0500] [Job 38] File(s) to be printed:
D [16/Dec/2006:23:42:13 -0500] [Job 38] <STDIN>
D [16/Dec/2006:23:42:13 -0500] [Job 38]
D [16/Dec/2006:23:42:13 -0500] [Job 38] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [16/Dec/2006:23:42:13 -0500] [Job 38]
D [16/Dec/2006:23:42:13 -0500] [Job 38] ================================================
D [16/Dec/2006:23:42:13 -0500] [Job 38]
D [16/Dec/2006:23:42:13 -0500] [Job 38] File: <STDIN>
D [16/Dec/2006:23:42:13 -0500] [Job 38]
D [16/Dec/2006:23:42:13 -0500] [Job 38] ================================================
D [16/Dec/2006:23:42:13 -0500] [Job 38]
D [16/Dec/2006:23:42:13 -0500] [Job 38] Reading PostScript input ...
D [16/Dec/2006:23:42:13 -0500] [Job 38] --> This document is DSC-conforming!
D [16/Dec/2006:23:42:13 -0500] [Job 38]
D [16/Dec/2006:23:42:13 -0500] [Job 38] -----------
D [16/Dec/2006:23:42:13 -0500] [Job 38] Found: %%BeginProlog
D [16/Dec/2006:23:42:13 -0500] [Job 38] 0 %%EOF
D [16/Dec/2006:23:42:13 -0500] [Job 38] Saw EOF!
D [16/Dec/2006:23:42:13 -0500] [Job 38] Found: %%EndProlog
D [16/Dec/2006:23:42:13 -0500] [Job 38]
D [16/Dec/2006:23:42:13 -0500] [Job 38] -----------
D [16/Dec/2006:23:42:13 -0500] [Job 38] Found: %%BeginSetup
D [16/Dec/2006:23:42:13 -0500] [Job 38] Found: %%BeginFeature: *PrintoutMode Normal
D [16/Dec/2006:23:42:13 -0500] [Job 38] Option: PrintoutMode=Normal --> Setting option
D [16/Dec/2006:23:42:13 -0500] [Job 38] Found: %% FoomaticRIPOptionSetting: PrintoutMode=Normal
D [16/Dec/2006:23:42:13 -0500] [Job 38] Option: PrintoutMode=Normal --> Setting option
D [16/Dec/2006:23:42:13 -0500] [Job 38] Found: %%BeginFeature: *Quality FromPrintoutMode
D [16/Dec/2006:23:42:13 -0500] [Job 38] Option: Quality=FromPrintoutMode --> Setting option
D [16/Dec/2006:23:42:13 -0500] [Job 38] Found: %% FoomaticRIPOptionSetting: Quality=@PrintoutMode
D [16/Dec/2006:23:42:13 -0500] [Job 38] Option: Quality=FromPrintoutMode --> Setting option
D [16/Dec/2006:23:42:13 -0500] [Job 38] Found: %%BeginFeature: *PageSize A4
D [16/Dec/2006:23:42:13 -0500] [Job 38] Option: PageSize=A4 --> Setting option
D [16/Dec/2006:23:42:13 -0500] [Job 38] Found: %% FoomaticRIPOptionSetting: PageSize=A4
D [16/Dec/2006:23:42:13 -0500] [Job 38] Option: PageSize=A4 --> Setting option
D [16/Dec/2006:23:42:13 -0500] [Job 38] Found: %%EndSetup
D [16/Dec/2006:23:42:13 -0500] [Job 38] Inserting PostScript code for CUPS' page accounting
D [16/Dec/2006:23:42:13 -0500] [Job 38]
D [16/Dec/2006:23:42:13 -0500] [Job 38] -----------
D [16/Dec/2006:23:42:13 -0500] [Job 38] New page:  1 1
D [16/Dec/2006:23:42:13 -0500] [Job 38] Inserting option code into "PageSetup" section.
D [16/Dec/2006:23:42:13 -0500] [Job 38] No page header or page header not DSC-conforming
D [16/Dec/2006:23:42:13 -0500] [Job 38] Stopping search for page header options
D [16/Dec/2006:23:42:13 -0500] [Job 38] Found:
D [16/Dec/2006:23:42:13 -0500] [Job 38] pageHeight sub                % Move down...
D [16/Dec/2006:23:42:13 -0500] [Job 38] --> Output goes directly to the renderer now.
D [16/Dec/2006:23:42:13 -0500] [Job 38]
D [16/Dec/2006:23:42:13 -0500] [Job 38]
D [16/Dec/2006:23:42:13 -0500] [Job 38] Starting renderer
D [16/Dec/2006:23:42:13 -0500] [Job 38] renderer PID kid4=17260
D [16/Dec/2006:23:42:13 -0500] [Job 38] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="Deskjet D4100" -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -r600 -sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=1 -dIjsUseOutputFD -sOutputFile=- -
D [16/Dec/2006:23:42:13 -0500] [Job 38] JCL: <job data>
D [16/Dec/2006:23:42:13 -0500] [Job 38]
D [16/Dec/2006:23:42:15 -0500] [Job 38] foomatic-gswrapper: gs '-sstdout=%stderr' '-dBATCH' '-dPARANOIDSAFER' '-dQUIET' '-dNOPAUSE' '-sDEVICE=ijs' '-sIjsServer=hpijs' '-sDeviceManufacturer=HEWLETT-PACKARD' '-sDeviceModel=Deskjet D4100' '-dDEVICEWIDTHPOINTS=595' '-dDEVICEHEIGHTPOINTS=842' '-r600' '-sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=1' '-dIjsUseOutputFD' '-sOutputFile=%stdout' '-'
D [16/Dec/2006:23:42:15 -0500] [Job 38] unable to set device=Deskjet D4100, err=16
D [16/Dec/2006:23:42:15 -0500] [Job 38] **** Unable to open the initial device, quitting.
D [16/Dec/2006:23:42:15 -0500] [Job 38] renderer return value: 1
D [16/Dec/2006:23:42:15 -0500] [Job 38] renderer received signal: 1
D [16/Dec/2006:23:42:15 -0500] [Job 38]
D [16/Dec/2006:23:42:15 -0500] [Job 38] Closing renderer
D [16/Dec/2006:23:42:15 -0500] [Job 38] unable to read client data err=-2
D [16/Dec/2006:23:42:15 -0500] [Job 38] tail process done writing data to STDOUT
D [16/Dec/2006:23:42:15 -0500] [Job 38] KID4 finished
D [16/Dec/2006:23:42:15 -0500] [Job 38] Process dying with "Possible error on renderer command line or PostScript error. Check options.", exit stat: 3
D [16/Dec/2006:23:42:15 -0500] [Job 38] error: Illegal seek (29)
D [16/Dec/2006:23:42:15 -0500] [Job 38] Possible error on renderer command line or PostScript error. Check options.
D [16/Dec/2006:23:42:15 -0500] [Job 38] KID4 exited with status 0
D [16/Dec/2006:23:42:15 -0500] [Job 38] KID3 exited with status 3
D [16/Dec/2006:23:42:15 -0500] [Job 38] Renderer exit stat: 3
D [16/Dec/2006:23:42:15 -0500] [Job 38] Renderer process finished
D [16/Dec/2006:23:42:15 -0500] [Job 38] Killing process 17259 (KID3)
D [16/Dec/2006:23:42:15 -0500] [Job 38] Process dying with "Error closing renderer", exit stat: 3
D [16/Dec/2006:23:42:15 -0500] [Job 38] error: Bad file descriptor (9)
D [16/Dec/2006:23:42:15 -0500] [Job 38] Error closing renderer
E [16/Dec/2006:23:42:15 -0500] PID 17256 stopped with status 3!
D [16/Dec/2006:23:42:15 -0500] [Job 38] Getting supported attributes...
D [16/Dec/2006:23:42:15 -0500] [Job 38] document-format-supported (27 values)
D [16/Dec/2006:23:42:15 -0500] [Job 38] [0] = "application/octet-stream"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [1] = "application/pdf"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [2] = "application/postscript"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [3] = "application/vnd.cups-postscript"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [4] = "application/vnd.cups-raw"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [5] = "application/vnd.hp-hpgl"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [6] = "application/x-cshell"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [7] = "application/x-csource"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [8] = "application/x-perl"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [9] = "application/x-shell"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [10] = "image/gif"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [11] = "image/jpeg"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [12] = "image/png"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [13] = "image/tiff"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [14] = "image/x-bitmap"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [15] = "image/x-photocd"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [16] = "image/x-portable-anymap"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [17] = "image/x-portable-bitmap"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [18] = "image/x-portable-graymap"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [19] = "image/x-portable-pixmap"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [20] = "image/x-sgi-rgb"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [21] = "image/x-sun-raster"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [22] = "image/x-xbitmap"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [23] = "image/x-xpixmap"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [24] = "text/html"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [25] = "text/plain"
D [16/Dec/2006:23:42:15 -0500] [Job 38] [26] = ""
D [16/Dec/2006:23:42:15 -0500] [Job 38] printer-uri = "http://192.168.2.22:631/printers/hp4160"
D [16/Dec/2006:23:42:15 -0500] [Job 38] requesting-user-name = "root"
D [16/Dec/2006:23:42:15 -0500] [Job 38] job-name = "Test Page"
E [16/Dec/2006:23:42:15 -0500] [Job 38] Print file was not accepted (client-error-bad-request)!
E [16/Dec/2006:23:42:15 -0500] PID 17257 stopped with status 1!
D [16/Dec/2006:23:42:15 -0500] UpdateJob: job 38, file 0 is complete.
D [16/Dec/2006:23:42:15 -0500] StopJob: id = 38, force = 0
I [16/Dec/2006:23:42:15 -0500] Saving printers.conf...
D [16/Dec/2006:23:42:15 -0500] StopJob: printer state is 5
D [16/Dec/2006:23:42:15 -0500] ReadClient: 5 GET /printers/HP_DJ_4160_IPP HTTP/1.1
D [16/Dec/2006:23:42:15 -0500] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 17265
I [16/Dec/2006:23:42:15 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=17265)
D [16/Dec/2006:23:42:15 -0500] SendCommand: 5 file=8
D [16/Dec/2006:23:42:15 -0500] AcceptClient: 7 from localhost:631.
D [16/Dec/2006:23:42:15 -0500] ReadClient: 7 POST / HTTP/1.1
D [16/Dec/2006:23:42:15 -0500] ProcessIPPRequest: 7 status_code=1
D [16/Dec/2006:23:42:15 -0500] ReadClient: 7 POST / HTTP/1.1
D [16/Dec/2006:23:42:15 -0500] ProcessIPPRequest: 7 status_code=1
D [16/Dec/2006:23:42:15 -0500] ReadClient: 7 POST / HTTP/1.1
D [16/Dec/2006:23:42:15 -0500] ProcessIPPRequest: 7 status_code=1
D [16/Dec/2006:23:42:15 -0500] CloseClient: 7


jonlake 12-17-2006 12:20 AM

I am now able to print from both computers, however, when a job is sent to the print queue and it starts processing, the lights on the printer alternately flash really fast. I hit the power button, the printer doesn't turn off, but the lights stop flashing. Then when I restart the printer in the cups web interface, the job prints just fine. If anyone has any suggestions how to make it so I don't have to hit the power button and restart the printer I would appreciate it.


All times are GMT -5. The time now is 02:09 PM.