Arch Linux

Searinox

Member

Registered: 2004-09-14

Posts: 44

printer stopped working after last cups update

Hi,
I own a HP deskjet 940c which worked fine with hpijs and cups until the last cups update.
After that it suddenly stopped working.
Here's my cups error_log (debug mode) when trying to print a test page:

I [26/Feb/2005:15:58:48 +0100] Job 52 queued on 'deskjet940c' by ''.
D [26/Feb/2005:15:58:48 +0100] Job 52 hold_until = 0
D [26/Feb/2005:15:58:48 +0100] StartJob(52, 0x8095108)
D [26/Feb/2005:15:58:48 +0100] StartJob() id = 52, file = 0/1
D [26/Feb/2005:15:58:48 +0100] job-sheets=none,none
D [26/Feb/2005:15:58:48 +0100] banner_page = 0
D [26/Feb/2005:15:58:48 +0100] StartJob: argv = "deskjet940c","52","","Test Page","1","","/var/spool/cups/d00052-001"
D [26/Feb/2005:15:58:48 +0100] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [26/Feb/2005:15:58:48 +0100] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [26/Feb/2005:15:58:48 +0100] StartJob: envp[2]="USER=root"
D [26/Feb/2005:15:58:48 +0100] StartJob: envp[3]="CHARSET=utf-8"
D [26/Feb/2005:15:58:48 +0100] StartJob: envp[4]="LANG=en_US"
D [26/Feb/2005:15:58:48 +0100] StartJob: envp[5]="PPD=/etc/cups/ppd/deskjet940c.ppd"
D [26/Feb/2005:15:58:48 +0100] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [26/Feb/2005:15:58:48 +0100] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [26/Feb/2005:15:58:48 +0100] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [26/Feb/2005:15:58:48 +0100] StartJob: envp[9]="CONTENT_TYPE=application/postscript"
D [26/Feb/2005:15:58:48 +0100] StartJob: envp[10]="DEVICE_URI=usb://HP/DeskJet%20940C?serial=CN16P6C1XXBH"
D [26/Feb/2005:15:58:48 +0100] StartJob: envp[11]="PRINTER=deskjet940c"
D [26/Feb/2005:15:58:48 +0100] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
D [26/Feb/2005:15:58:48 +0100] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [26/Feb/2005:15:58:48 +0100] StartJob: envp[14]="CUPS_SERVER=localhost"
D [26/Feb/2005:15:58:48 +0100] StartJob: envp[15]="IPP_PORT=631"
D [26/Feb/2005:15:58:48 +0100] StartJob: statusfds = [ 9 10 ]
D [26/Feb/2005:15:58:48 +0100] StartJob: filterfds[1] = [ 11 -1 ]
D [26/Feb/2005:15:58:48 +0100] StartJob: filter = "/usr/lib/cups/filter/pstops"
D [26/Feb/2005:15:58:48 +0100] StartJob: filterfds[0] = [ 12 13 ]
D [26/Feb/2005:15:58:48 +0100] start_process("/usr/lib/cups/filter/pstops", 0xbffee210, 0xbffed580, 11, 13, 10)
I [26/Feb/2005:15:58:48 +0100] Started filter /usr/lib/cups/filter/pstops (PID 8209) for job 52.
D [26/Feb/2005:15:58:48 +0100] StartJob: filter = "/usr/lib/cups/filter/foomatic-rip"
D [26/Feb/2005:15:58:48 +0100] StartJob: filterfds[1] = [ 11 14 ]
D [26/Feb/2005:15:58:48 +0100] start_process("/usr/lib/cups/filter/foomatic-rip", 0xbffee210, 0xbffed580, 12, 14, 10)
I [26/Feb/2005:15:58:48 +0100] Started filter /usr/lib/cups/filter/foomatic-rip (PID 8210) for job 52.
D [26/Feb/2005:15:58:48 +0100] StartJob: backend = "/usr/lib/cups/backend/usb"
D [26/Feb/2005:15:58:48 +0100] StartJob: filterfds[0] = [ -1 12 ]
D [26/Feb/2005:15:58:48 +0100] start_process("/usr/lib/cups/backend/usb", 0xbffee210, 0xbffed580, 11, 12, 10)
I [26/Feb/2005:15:58:48 +0100] Started backend /usr/lib/cups/backend/usb (PID 8211) for job 52.
D [26/Feb/2005:15:58:48 +0100] ProcessIPPRequest: 8 status_code=0
D [26/Feb/2005:15:58:48 +0100] CloseClient: 8
D [26/Feb/2005:15:58:48 +0100] [Job 52] Page = 595x842; 10,36 to 585,833
D [26/Feb/2005:15:58:48 +0100] [Job 52] slowcollate=0, slowduplex=0, sloworder=0
D [26/Feb/2005:15:58:48 +0100] [Job 52] 0 %%BoundingBox: 0 0 612 792
D [26/Feb/2005:15:58:48 +0100] [Job 52] 0 %%Pages: 1
D [26/Feb/2005:15:58:48 +0100] [Job 52] 0 %%LanguageLevel: 1
D [26/Feb/2005:15:58:48 +0100] [Job 52] 0 %%DocumentData: Clean7Bit
D [26/Feb/2005:15:58:48 +0100] [Job 52] 0 %%DocumentSuppliedResources: procset testprint/1.1
D [26/Feb/2005:15:58:48 +0100] [Job 52] 0 %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman
D [26/Feb/2005:15:58:48 +0100] [Job 52] 0 %%Creator: Michael Sweet, Easy Software Products
D [26/Feb/2005:15:58:48 +0100] [Job 52] 0 %%CreationDate: May 11, 1999
D [26/Feb/2005:15:58:48 +0100] [Job 52] 0 %%Title: Test Page
D [26/Feb/2005:15:58:48 +0100] [Job 52] 0 %%EndComments
D [26/Feb/2005:15:58:48 +0100] [Job 52] 0 %%BeginProlog
D [26/Feb/2005:15:58:48 +0100] [Job 52] 0 %%BeginResource procset testprint 1.1 0
D [26/Feb/2005:15:58:48 +0100] [Job 52] 0 %%EndResource
D [26/Feb/2005:15:58:48 +0100] [Job 52] 0 %%EndProlog
D [26/Feb/2005:15:58:48 +0100] [Job 52] 0 %%Page: 1 1
D [26/Feb/2005:15:58:48 +0100] [Job 52] 0 %%Page: 1 1
D [26/Feb/2005:15:58:48 +0100] [Job 52] pw = 575.6, pl = 797.0
D [26/Feb/2005:15:58:48 +0100] [Job 52] PageLeft = 9.7, PageRight = 585.3
D [26/Feb/2005:15:58:48 +0100] [Job 52] PageTop = 833.0, PageBottom = 36.0
D [26/Feb/2005:15:58:48 +0100] [Job 52] PageWidth = 595.0, PageLength = 842.0
D [26/Feb/2005:15:58:48 +0100] [Job 52] Printer using device file "/dev/usb/lp0"...
D [26/Feb/2005:15:58:48 +0100] [Job 52] LPGETSTATUS returned a port status of 18...
D [26/Feb/2005:15:58:49 +0100] ReadClient: 5 GET /favicon.ico HTTP/1.1
D [26/Feb/2005:15:58:49 +0100] SendError: 5 code=404 (Not Found)
D [26/Feb/2005:15:58:49 +0100] CloseClient: 5
D [26/Feb/2005:15:58:49 +0100] [Job 52] foomatic-rip version $Revision: 3.43.2.2 $ running...
D [26/Feb/2005:15:58:49 +0100] [Job 52] Parsing PPD file ...
D [26/Feb/2005:15:58:49 +0100] [Job 52] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"
D [26/Feb/2005:15:58:49 +0100] [Job 52] Added option ColorSpace
D [26/Feb/2005:15:58:49 +0100] [Job 52] Added option Resolution
D [26/Feb/2005:15:58:49 +0100] [Job 52] Added option PageSize
D [26/Feb/2005:15:58:49 +0100] [Job 52] Added option PageRegion
D [26/Feb/2005:15:58:49 +0100] [Job 52] Added option Model
D [26/Feb/2005:15:58:49 +0100] [Job 52] Added option PrintoutMode
D [26/Feb/2005:15:58:49 +0100] [Job 52] Added option ImageableArea
D [26/Feb/2005:15:58:49 +0100] [Job 52] Added option PaperDimension
D [26/Feb/2005:15:58:49 +0100] [Job 52] Added option Duplex
D [26/Feb/2005:15:58:49 +0100] [Job 52] Added option Quality
D [26/Feb/2005:15:58:49 +0100] [Job 52] Added option Font
D [26/Feb/2005:15:58:49 +0100] [Job 52] 
D [26/Feb/2005:15:58:49 +0100] [Job 52] Parameter Summary
D [26/Feb/2005:15:58:49 +0100] [Job 52] -----------------
D [26/Feb/2005:15:58:49 +0100] [Job 52] 
D [26/Feb/2005:15:58:49 +0100] [Job 52] Spooler: cups
D [26/Feb/2005:15:58:49 +0100] [Job 52] Printer: deskjet940c
D [26/Feb/2005:15:58:49 +0100] [Job 52] PPD file: /etc/cups/ppd/deskjet940c.ppd
D [26/Feb/2005:15:58:49 +0100] [Job 52] Printer model: HP DeskJet 940C Foomatic/hpijs (recommended)
D [26/Feb/2005:15:58:49 +0100] [Job 52] Job title: Test Page
D [26/Feb/2005:15:58:49 +0100] [Job 52] File(s) to be printed: 
D [26/Feb/2005:15:58:49 +0100] [Job 52] <STDIN>
D [26/Feb/2005:15:58:49 +0100] [Job 52] 
D [26/Feb/2005:15:58:49 +0100] [Job 52] 
D [26/Feb/2005:15:58:49 +0100] [Job 52] ================================================
D [26/Feb/2005:15:58:49 +0100] [Job 52] 
D [26/Feb/2005:15:58:49 +0100] [Job 52] File: <STDIN>
D [26/Feb/2005:15:58:49 +0100] [Job 52] 
D [26/Feb/2005:15:58:49 +0100] [Job 52] ================================================
D [26/Feb/2005:15:58:49 +0100] [Job 52] 
D [26/Feb/2005:15:58:49 +0100] [Job 52] Reading PostScript input ...
D [26/Feb/2005:15:58:49 +0100] [Job 52] --> This document is DSC-conforming!
D [26/Feb/2005:15:58:49 +0100] [Job 52] 
D [26/Feb/2005:15:58:49 +0100] [Job 52] -----------
D [26/Feb/2005:15:58:49 +0100] [Job 52] Found: %%BeginProlog
D [26/Feb/2005:15:58:49 +0100] [Job 52] 0 %%EOF
D [26/Feb/2005:15:58:49 +0100] [Job 52] Saw EOF!
D [26/Feb/2005:15:58:49 +0100] [Job 52] Found: %%EndProlog
D [26/Feb/2005:15:58:49 +0100] [Job 52] 
D [26/Feb/2005:15:58:49 +0100] [Job 52] -----------
D [26/Feb/2005:15:58:49 +0100] [Job 52] Found: %%BeginSetup
D [26/Feb/2005:15:58:49 +0100] [Job 52] Inserting PostScript code for CUPS' page accounting
D [26/Feb/2005:15:58:49 +0100] [Job 52] Found: %%BeginFeature: *PrintoutMode Normal
D [26/Feb/2005:15:58:49 +0100] [Job 52] Option: PrintoutMode=Normal --> Setting option
D [26/Feb/2005:15:58:49 +0100] [Job 52] Found: %% FoomaticRIPOptionSetting: PrintoutMode=Normal
D [26/Feb/2005:15:58:49 +0100] [Job 52] Option: PrintoutMode=Normal --> Setting option
D [26/Feb/2005:15:58:49 +0100] [Job 52] Found: %%BeginFeature: *Quality FromPrintoutMode
D [26/Feb/2005:15:58:49 +0100] [Job 52] Option: Quality=FromPrintoutMode --> Setting option
D [26/Feb/2005:15:58:49 +0100] [Job 52] Found: %% FoomaticRIPOptionSetting: Quality=@PrintoutMode
D [26/Feb/2005:15:58:49 +0100] [Job 52] Option: Quality=FromPrintoutMode --> Setting option
D [26/Feb/2005:15:58:49 +0100] [Job 52] Found: %%BeginFeature: *PageSize A4
D [26/Feb/2005:15:58:49 +0100] [Job 52] Option: PageSize=A4 --> Setting option
D [26/Feb/2005:15:58:49 +0100] [Job 52] Found: %% FoomaticRIPOptionSetting: PageSize=A4
D [26/Feb/2005:15:58:49 +0100] [Job 52] Option: PageSize=A4 --> Setting option
D [26/Feb/2005:15:58:49 +0100] [Job 52] Found: %%BeginFeature: *Duplex None
D [26/Feb/2005:15:58:49 +0100] [Job 52] Option: Duplex=None --> Setting option
D [26/Feb/2005:15:58:49 +0100] [Job 52] Found: %% FoomaticRIPOptionSetting: Duplex=None
D [26/Feb/2005:15:58:49 +0100] [Job 52] Option: Duplex=None --> Setting option
D [26/Feb/2005:15:58:49 +0100] [Job 52] Found: %%EndSetup
D [26/Feb/2005:15:58:49 +0100] [Job 52] 
D [26/Feb/2005:15:58:49 +0100] [Job 52] -----------
D [26/Feb/2005:15:58:49 +0100] [Job 52] New page:  1 1
D [26/Feb/2005:15:58:49 +0100] [Job 52] Inserting option code into "PageSetup" section.
D [26/Feb/2005:15:58:49 +0100] [Job 52] No page header or page header not DSC-conforming
D [26/Feb/2005:15:58:49 +0100] [Job 52] Stopping search for page header options
D [26/Feb/2005:15:58:49 +0100] [Job 52] Found:
D [26/Feb/2005:15:58:49 +0100] [Job 52] pageHeight sub            % Move down...
D [26/Feb/2005:15:58:49 +0100] [Job 52] --> Output goes directly to the renderer now.
D [26/Feb/2005:15:58:49 +0100] [Job 52] 
D [26/Feb/2005:15:58:49 +0100] [Job 52] 
D [26/Feb/2005:15:58:49 +0100] [Job 52] Starting renderer
D [26/Feb/2005:15:58:49 +0100] [Job 52] renderer PID kid4=8213
D [26/Feb/2005:15:58:49 +0100] [Job 52] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="DESKJET 940" -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -dDuplex=false -r300 -sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=2 -dIjsUseOutputFD -sOutputFile=- -
D [26/Feb/2005:15:58:49 +0100] [Job 52] JCL: <job data> 
D [26/Feb/2005:15:58:49 +0100] [Job 52] 
D [26/Feb/2005:15:58:49 +0100] [Job 52] foomatic-gswrapper: gs '-dBATCH' '-dPARANOIDSAFER' '-dQUIET' '-dNOPAUSE' '-sDEVICE=ijs' '-sIjsServer=hpijs' '-sDeviceManufacturer=HEWLETT-PACKARD' '-sDeviceModel=DESKJET 940' '-dDEVICEWIDTHPOINTS=595' '-dDEVICEHEIGHTPOINTS=842' '-dDuplex=false' '-r300' '-sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=2' '-dIjsUseOutputFD' '-sOutputFile=| cat >&3' '/dev/fd/0' 3>&1 1>&2
D [26/Feb/2005:15:58:49 +0100] [Job 52] ESP Ghostscript 7.07.1: Could not open the scratch file /var/spool/cups/tmp/gs_4bzwc2.
D [26/Feb/2005:15:58:49 +0100] [Job 52] **** Unable to open the initial device, quitting.
D [26/Feb/2005:15:58:49 +0100] [Job 52] renderer return value: 1
D [26/Feb/2005:15:58:49 +0100] [Job 52] renderer received signal: 1
D [26/Feb/2005:15:58:49 +0100] [Job 52] Process dying with "Possible error on renderer command line or PostScript error. Check options.", exit stat: 3
D [26/Feb/2005:15:58:49 +0100] [Job 52] Possible error on renderer command line or PostScript error. Check options.
D [26/Feb/2005:15:58:49 +0100] [Job 52] tail process done writing data to STDOUT
D [26/Feb/2005:15:58:49 +0100] [Job 52] KID4 finished
D [26/Feb/2005:15:58:49 +0100] [Job 52] 
D [26/Feb/2005:15:58:49 +0100] [Job 52] Closing renderer
D [26/Feb/2005:15:58:49 +0100] [Job 52] KID3 exited with status 3
D [26/Feb/2005:15:58:49 +0100] [Job 52] Renderer exit stat: 3
D [26/Feb/2005:15:58:49 +0100] [Job 52] Renderer process finished
D [26/Feb/2005:15:58:49 +0100] [Job 52] Killing process 8212 (KID3)
D [26/Feb/2005:15:58:49 +0100] [Job 52] Process dying with "Error closing renderer", exit stat: 3
D [26/Feb/2005:15:58:49 +0100] [Job 52] Error closing renderer
E [26/Feb/2005:15:58:49 +0100] PID 8210 stopped with status 3!
D [26/Feb/2005:15:58:49 +0100] UpdateJob: job 52, file 0 is complete.
D [26/Feb/2005:15:58:49 +0100] CancelJob: id = 52
D [26/Feb/2005:15:58:49 +0100] StopJob: id = 52, force = 0
D [26/Feb/2005:15:58:49 +0100] StopJob: printer state is 3
D [26/Feb/2005:15:58:50 +0100] AcceptClient: 5 from localhost:631.
D [26/Feb/2005:15:58:50 +0100] ReadClient: 5 GET /printers/deskjet940c HTTP/1.1
D [26/Feb/2005:15:58:50 +0100] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 8216
I [26/Feb/2005:15:58:50 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=8216)
D [26/Feb/2005:15:58:50 +0100] SendCommand: 5 file=8
D [26/Feb/2005:15:58:50 +0100] AcceptClient: 7 from localhost:631.
D [26/Feb/2005:15:58:50 +0100] ReadClient: 7 POST / HTTP/1.1
D [26/Feb/2005:15:58:50 +0100] ProcessIPPRequest: 7 status_code=1
D [26/Feb/2005:15:58:50 +0100] ReadClient: 7 POST / HTTP/1.1
D [26/Feb/2005:15:58:50 +0100] ProcessIPPRequest: 7 status_code=1
D [26/Feb/2005:15:58:50 +0100] ReadClient: 7 POST / HTTP/1.1
D [26/Feb/2005:15:58:50 +0100] ProcessIPPRequest: 7 status_code=1
D [26/Feb/2005:15:58:50 +0100] CloseClient: 7
D [26/Feb/2005:15:58:50 +0100] ReadClient: 5 GET /favicon.ico HTTP/1.1
D [26/Feb/2005:15:58:50 +0100] SendError: 5 code=404 (Not Found)
D [26/Feb/2005:15:58:50 +0100] CloseClient: 5
D [26/Feb/2005:15:58:52 +0100] AcceptClient: 5 from localhost:631.
D [26/Feb/2005:15:58:52 +0100] ReadClient: 5 GET /printers/deskjet940c?which_jobs=completed HTTP/1.1
D [26/Feb/2005:15:58:52 +0100] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 8217
I [26/Feb/2005:15:58:52 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=8217)
D [26/Feb/2005:15:58:52 +0100] SendCommand: 5 file=8
D [26/Feb/2005:15:58:52 +0100] AcceptClient: 7 from localhost:631.
D [26/Feb/2005:15:58:52 +0100] ReadClient: 7 POST / HTTP/1.1
D [26/Feb/2005:15:58:52 +0100] ProcessIPPRequest: 7 status_code=1
D [26/Feb/2005:15:58:52 +0100] ReadClient: 7 POST / HTTP/1.1
D [26/Feb/2005:15:58:52 +0100] ProcessIPPRequest: 7 status_code=1
D [26/Feb/2005:15:58:52 +0100] ReadClient: 7 POST / HTTP/1.1
D [26/Feb/2005:15:58:52 +0100] ProcessIPPRequest: 7 status_code=1
D [26/Feb/2005:15:58:52 +0100] CloseClient: 7
D [26/Feb/2005:15:58:52 +0100] ReadClient: 5 GET /favicon.ico HTTP/1.1
D [26/Feb/2005:15:58:52 +0100] SendError: 5 code=404 (Not Found)
D [26/Feb/2005:15:58:52 +0100] CloseClient: 5

I hope you can help me.

zezaz

Member

From: Bordeaux, France

Registered: 2004-04-26

Posts: 80

Website

Re: printer stopped working after last cups update

pacman probably updated your printers.conf file. You can have confirmation of that by looking at the pacman log in /var/log/pacman.log.

This should work:
- stop cups
- check your /etc/cups/printers.conf file, and re-add your printer in it. A printers.conf.pacsave is in the same directory, it was left here by pacman.
- restart cups

That should be enough. And remember: when pacman updates config files, he does tell you so. But it is your job to check that they are still correct. 

Searinox

Member

Registered: 2004-09-14

Posts: 44

Re: printer stopped working after last cups update

Thanks, but that didn't help
I already re-added the printer but the error stays the same.

Searinox

Member

Registered: 2004-09-14

Posts: 44

Re: printer stopped working after last cups update

Solved!
Somehow, the /var/spool/cups/tmp directory was deleted.
After recreating the directory, everything went fine again.