FS#56706 - hplip 3.17.11-1 breaks work of HP LaserJet P1102

Attached to Project: Arch Linux
Opened by Dmytro Bagrii (dimich) - Wednesday, 13 December 2017, 17:09 GMT
Last edited by Andreas Radke (AndyRTR) - Thursday, 14 December 2017, 18:14 GMT
Task Type Bug Report
Category Packages: Extra
Status Closed
Assigned To Andreas Radke (AndyRTR)
Architecture x86_64
Severity Medium
Priority Normal
Reported Version
Due in Version Undecided
Due Date Undecided
Percent Complete 100%
Votes 1
Private No

Details

After last hplip update HP LaserJet P1102 stopped to work.
Cups web interface says "Printer command job completed" but nothing happens.
Downgrade to hplip-3.17.10-2 doesn't help. Maybe the reason is hplip-plugin from AUR? Downgrading hplip-plugin to 3.17.10 doesn't help as well.
This task depends upon

Closed by  Andreas Radke (AndyRTR)
Thursday, 14 December 2017, 18:14 GMT
Reason for closing:  Not a bug
Comment by Dmytro Bagrii (dimich) - Wednesday, 13 December 2017, 17:20 GMT
Error log is:

D [13/Dec/2017:18:34:47 +0200] [Job 204] File: <STDIN>
D [13/Dec/2017:18:34:47 +0200] [Job 204] ================================================
D [13/Dec/2017:18:34:47 +0200] [Job 204] PDF template file doesn\'t have form. It\'s okay.
D [13/Dec/2017:18:34:47 +0200] [Job 204] PID 1183 (/usr/lib/cups/filter/bannertopdf) exited with no errors.
D [13/Dec/2017:18:34:47 +0200] [Job 204] PAGE: 1 1
D [13/Dec/2017:18:34:47 +0200] [Job 204] Filetype: PDF
D [13/Dec/2017:18:34:47 +0200] [Job 204] Storing temporary files in /var/spool/cups/tmp
D [13/Dec/2017:18:34:47 +0200] [Job 204] PID 1184 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [13/Dec/2017:18:34:47 +0200] [Job 204] File contains 1 pages
D [13/Dec/2017:18:34:47 +0200] [Job 204] Starting renderer with command: gs -dShowAcroForm -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -sDeviceManufacturer=\"HEWLETT-PACKARD\" -sDeviceModel=\"HP LaserJet 1018\" -r600 -sIjsParams=Quality:Quality=0,Quality:ColorMode=0,Quality:PenSet=0,PS:MediaPosition=7 -dIjsUseOutputFD -sOutputFile=- /var/spool/cups/tmp/foomatic-5SbhFe
D [13/Dec/2017:18:34:47 +0200] [Job 204] Starting process \"kid3\" (generation 1)
D [13/Dec/2017:18:34:47 +0200] [Job 204] Starting process \"kid4\" (generation 2)
D [13/Dec/2017:18:34:47 +0200] [Job 204] Starting process \"renderer\" (generation 2)
D [13/Dec/2017:18:34:47 +0200] [Job 204] JCL: \033%-12345X@PJL
D [13/Dec/2017:18:34:47 +0200] [Job 204] <job data>
D [13/Dec/2017:18:34:47 +0200] [Job 204] sh: hpijs: command not found
D [13/Dec/2017:18:34:47 +0200] [Job 204] GPL Ghostscript 9.22: Can\'t start ijs server \"hpijs\"
D [13/Dec/2017:18:34:47 +0200] [Job 204] **** Unable to open the initial device, quitting.
D [13/Dec/2017:18:34:47 +0200] [Job 204] renderer exited with status 1
D [13/Dec/2017:18:34:47 +0200] [Job 204] Possible error on renderer command line or PostScript error. Check options.Kid3 exit status: 3
D [13/Dec/2017:18:34:47 +0200] [Job 204] prnt/backend/hp.c 919: ERROR: null print job total=0
D [13/Dec/2017:18:34:47 +0200] [Job 204] PID 1185 (/usr/lib/cups/filter/foomatic-rip) stopped with status 9.
D [13/Dec/2017:18:34:47 +0200] [Job 204] Hint: Try setting the LogLevel to "debug" to find out more.
D [13/Dec/2017:18:34:47 +0200] [Job 204] PID 1186 (/usr/lib/cups/backend/hp) exited with no errors.
D [13/Dec/2017:18:34:47 +0200] [Job 204] End of messages
D [13/Dec/2017:18:34:47 +0200] [Job 204] printer-state=3(idle)
D [13/Dec/2017:18:34:47 +0200] [Job 204] printer-state-message="Filter failed"
D [13/Dec/2017:18:34:47 +0200] [Job 204] printer-state-reasons=none
E [13/Dec/2017:18:40:08 +0200] Unable to open listen socket for address [v1.::1]:631 - Address family not supported by protocol.
E [13/Dec/2017:18:40:39 +0200] [Client 7] Returning IPP client-error-not-possible for Cancel-Job (ipp://localhost/jobs/206) from localhost
E [13/Dec/2017:18:42:14 +0200] Unable to open listen socket for address [v1.::1]:631 - Address family not supported by protocol.
E [13/Dec/2017:18:53:39 +0200] Unable to open listen socket for address [v1.::1]:631 - Address family not supported by protocol.
E [13/Dec/2017:19:00:26 +0200] Unable to open listen socket for address [v1.::1]:631 - Address family not supported by protocol.
E [13/Dec/2017:19:02:13 +0200] Unable to open listen socket for address [v1.::1]:631 - Address family not supported by protocol.

Is it IPv6 support mandatory now?
Comment by Dmytro Bagrii (dimich) - Wednesday, 13 December 2017, 17:29 GMT
Enabling IPv6 didn't help.
Full error_log:

E [13/Dec/2017:19:25:59 +0200] [Client 9] Returning IPP client-error-not-possible for Cancel-Job (ipp://localhost/jobs/216) from localhost
E [13/Dec/2017:19:26:19 +0200] [Job 217] Job stopped due to filter errors; please consult the error_log file for details.
D [13/Dec/2017:19:26:19 +0200] [Job 217] The following messages were recorded from 19:26:17 to 19:26:19
D [13/Dec/2017:19:26:19 +0200] [Job 217] Applying default options...
D [13/Dec/2017:19:26:19 +0200] [Job 217] Adding start banner page "none".
D [13/Dec/2017:19:26:19 +0200] [Job 217] Adding end banner page "none".
D [13/Dec/2017:19:26:19 +0200] [Job 217] File of type application/vnd.cups-pdf-banner queued by "root".
D [13/Dec/2017:19:26:19 +0200] [Job 217] hold_until=0
D [13/Dec/2017:19:26:19 +0200] [Job 217] Queued on "P1102" by "root".
D [13/Dec/2017:19:26:19 +0200] [Job 217] time-at-processing=1513185977
D [13/Dec/2017:19:26:19 +0200] [Job 217] 3 filters for job:
D [13/Dec/2017:19:26:19 +0200] [Job 217] bannertopdf (application/vnd.cups-pdf-banner to application/pdf, cost 32)
D [13/Dec/2017:19:26:19 +0200] [Job 217] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)
D [13/Dec/2017:19:26:19 +0200] [Job 217] foomatic-rip (application/vnd.cups-pdf to printer/P1102, cost 0)
D [13/Dec/2017:19:26:19 +0200] [Job 217] job-sheets=none,none
D [13/Dec/2017:19:26:19 +0200] [Job 217] argv[0]="P1102"
D [13/Dec/2017:19:26:19 +0200] [Job 217] argv[1]="217"
D [13/Dec/2017:19:26:19 +0200] [Job 217] argv[2]="root"
D [13/Dec/2017:19:26:19 +0200] [Job 217] argv[3]="Test Page"
D [13/Dec/2017:19:26:19 +0200] [Job 217] argv[4]="1"
D [13/Dec/2017:19:26:19 +0200] [Job 217] argv[5]="job-uuid=urn:uuid:86eef430-45e8-337c-4925-fb254e5d206c job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1513185977 time-at-processing=1513185977"
D [13/Dec/2017:19:26:19 +0200] [Job 217] argv[6]="/var/spool/cups/d00217-001"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[7]="CUPS_STATEDIR=/run/cups"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[8]="HOME=/var/spool/cups/tmp"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[10]="SERVER_ADMIN=root@dimich"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[11]="SOFTWARE=CUPS/2.2.6"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[13]="USER=root"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[14]="CUPS_MAX_MESSAGE=2047"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[15]="CUPS_SERVER=/run/cups/cups.sock"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[17]="IPP_PORT=631"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[18]="CHARSET=utf-8"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[19]="LANG=en_US.UTF-8"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[20]="PPD=/etc/cups/ppd/P1102.ppd"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[21]="RIP_MAX_CACHE=128m"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[22]="CONTENT_TYPE=application/vnd.cups-pdf-banner"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[23]="DEVICE_URI=hp:/usb/HP_LaserJet_Professional_P1102?serial=000000000Q8D6VDHPR1a"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[24]="PRINTER_INFO=HP LaserJet Professional P1102"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[25]="PRINTER_LOCATION="
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[26]="PRINTER=P1102"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[27]="PRINTER_STATE_REASONS=none"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[28]="CUPS_FILETYPE=document"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-pdf"
D [13/Dec/2017:19:26:19 +0200] [Job 217] envp[30]="AUTH_I****"
D [13/Dec/2017:19:26:19 +0200] [Job 217] Started filter /usr/lib/cups/filter/bannertopdf (PID 1017)
D [13/Dec/2017:19:26:19 +0200] [Job 217] Started filter /usr/lib/cups/filter/pdftopdf (PID 1018)
D [13/Dec/2017:19:26:19 +0200] [Job 217] Started filter /usr/lib/cups/filter/foomatic-rip (PID 1019)
D [13/Dec/2017:19:26:19 +0200] [Job 217] Started backend /usr/lib/cups/backend/hp (PID 1020)
D [13/Dec/2017:19:26:19 +0200] [Job 217] pdftopdf: Last filter determined by the PPD: foomatic-rip; FINAL_CONTENT_TYPE: application/vnd.cups-pdf => pdftopdf will log pages in page_log.
D [13/Dec/2017:19:26:19 +0200] [Job 217] Calling FindDeviceById(cups-P1102)
D [13/Dec/2017:19:26:19 +0200] [Job 217] Found device /org/freedesktop/ColorManager/devices/cups_P1102
D [13/Dec/2017:19:26:19 +0200] [Job 217] Calling org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors)
D [13/Dec/2017:19:26:19 +0200] [Job 217] \'CM Color Calibration\' Mode in SPOOLER-LESS: Off
D [13/Dec/2017:19:26:19 +0200] [Job 217] Getting input from file
D [13/Dec/2017:19:26:19 +0200] [Job 217] foomatic-rip version 1.17.9 running...
D [13/Dec/2017:19:26:19 +0200] [Job 217] Parsing PPD file ...
D [13/Dec/2017:19:26:19 +0200] [Job 217] Added option ColorSpace
D [13/Dec/2017:19:26:19 +0200] [Job 217] Added option Resolution
D [13/Dec/2017:19:26:19 +0200] [Job 217] Added option PageSize
D [13/Dec/2017:19:26:19 +0200] [Job 217] Added option Model
D [13/Dec/2017:19:26:19 +0200] [Job 217] Added option PrintoutMode
D [13/Dec/2017:19:26:19 +0200] [Job 217] Added option MediaType
D [13/Dec/2017:19:26:19 +0200] [Job 217] Added option InputSlot
D [13/Dec/2017:19:26:19 +0200] [Job 217] Added option Quality
D [13/Dec/2017:19:26:19 +0200] [Job 217] Added option ImageableArea
D [13/Dec/2017:19:26:19 +0200] [Job 217] Added option PaperDimension
D [13/Dec/2017:19:26:19 +0200] [Job 217] Added option Font
D [13/Dec/2017:19:26:19 +0200] [Job 217] Parameter Summary
D [13/Dec/2017:19:26:19 +0200] [Job 217] -----------------
D [13/Dec/2017:19:26:19 +0200] [Job 217] Spooler: cups
D [13/Dec/2017:19:26:19 +0200] [Job 217] Printer: P1102
D [13/Dec/2017:19:26:19 +0200] [Job 217] Shell: /bin/bash
D [13/Dec/2017:19:26:19 +0200] [Job 217] PPD file: /etc/cups/ppd/P1102.ppd
D [13/Dec/2017:19:26:19 +0200] [Job 217] ATTR file:
D [13/Dec/2017:19:26:19 +0200] [Job 217] Printer model: HP LaserJet Professional p1102 hpijs, 3.16.5, requires proprietary plugin
D [13/Dec/2017:19:26:19 +0200] [Job 217] Job title: Test Page
D [13/Dec/2017:19:26:19 +0200] [Job 217] File(s) to be printed:
D [13/Dec/2017:19:26:19 +0200] [Job 217] <STDIN>
D [13/Dec/2017:19:26:19 +0200] [Job 217] Ghostscript extra search path (\'GS_LIB\'): /usr/share/cups/fonts
D [13/Dec/2017:19:26:19 +0200] [Job 217] Printing system options:
D [13/Dec/2017:19:26:19 +0200] [Job 217] Pondering option \'job-uuid=urn:uuid:86eef430-45e8-337c-4925-fb254e5d206c\'
D [13/Dec/2017:19:26:19 +0200] [Job 217] Unknown option job-uuid=urn:uuid:86eef430-45e8-337c-4925-fb254e5d206c.
D [13/Dec/2017:19:26:19 +0200] [Job 217] Pondering option \'job-originating-host-name=localhost\'
D [13/Dec/2017:19:26:19 +0200] [Job 217] Unknown option job-originating-host-name=localhost.
D [13/Dec/2017:19:26:19 +0200] [Job 217] Pondering option \'date-time-at-creation=\'
D [13/Dec/2017:19:26:19 +0200] [Job 217] Unknown option date-time-at-creation=.
D [13/Dec/2017:19:26:19 +0200] [Job 217] Pondering option \'date-time-at-processing=\'
D [13/Dec/2017:19:26:19 +0200] [Job 217] Unknown option date-time-at-processing=.
D [13/Dec/2017:19:26:19 +0200] [Job 217] Pondering option \'time-at-creation=1513185977\'
D [13/Dec/2017:19:26:19 +0200] [Job 217] Unknown option time-at-creation=1513185977.
D [13/Dec/2017:19:26:19 +0200] [Job 217] Pondering option \'time-at-processing=1513185977\'
D [13/Dec/2017:19:26:19 +0200] [Job 217] Unknown option time-at-processing=1513185977.
D [13/Dec/2017:19:26:19 +0200] [Job 217] CM Color Calibration Mode in CUPS: Off
D [13/Dec/2017:19:26:19 +0200] [Job 217] Options from the PPD file:
D [13/Dec/2017:19:26:19 +0200] [Job 217] ================================================
D [13/Dec/2017:19:26:19 +0200] [Job 217] File: <STDIN>
D [13/Dec/2017:19:26:19 +0200] [Job 217] ================================================
D [13/Dec/2017:19:26:19 +0200] [Job 217] PDF template file doesn\'t have form. It\'s okay.
D [13/Dec/2017:19:26:19 +0200] [Job 217] PID 1017 (/usr/lib/cups/filter/bannertopdf) exited with no errors.
D [13/Dec/2017:19:26:19 +0200] [Job 217] PAGE: 1 1
D [13/Dec/2017:19:26:19 +0200] [Job 217] PID 1018 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [13/Dec/2017:19:26:19 +0200] [Job 217] Filetype: PDF
D [13/Dec/2017:19:26:19 +0200] [Job 217] Storing temporary files in /var/spool/cups/tmp
D [13/Dec/2017:19:26:19 +0200] [Job 217] File contains 1 pages
D [13/Dec/2017:19:26:19 +0200] [Job 217] Starting renderer with command: gs -dShowAcroForm -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -sDeviceManufacturer=\"HEWLETT-PACKARD\" -sDeviceModel=\"HP LaserJet 1018\" -r600 -sIjsParams=Quality:Quality=0,Quality:ColorMode=0,Quality:PenSet=0,PS:MediaPosition=7 -dIjsUseOutputFD -sOutputFile=- /var/spool/cups/tmp/foomatic-5mrmFQ
D [13/Dec/2017:19:26:19 +0200] [Job 217] Starting process \"kid3\" (generation 1)
D [13/Dec/2017:19:26:19 +0200] [Job 217] Starting process \"kid4\" (generation 2)
D [13/Dec/2017:19:26:19 +0200] [Job 217] Starting process \"renderer\" (generation 2)
D [13/Dec/2017:19:26:19 +0200] [Job 217] JCL: \033%-12345X@PJL
D [13/Dec/2017:19:26:19 +0200] [Job 217] <job data>
D [13/Dec/2017:19:26:19 +0200] [Job 217] sh: hpijs: command not found
D [13/Dec/2017:19:26:19 +0200] [Job 217] GPL Ghostscript 9.22: Can\'t start ijs server \"hpijs\"
D [13/Dec/2017:19:26:19 +0200] [Job 217] **** Unable to open the initial device, quitting.
D [13/Dec/2017:19:26:19 +0200] [Job 217] renderer exited with status 1
D [13/Dec/2017:19:26:19 +0200] [Job 217] Possible error on renderer command line or PostScript error. Check options.Kid3 exit status: 3
D [13/Dec/2017:19:26:19 +0200] [Job 217] prnt/backend/hp.c 919: ERROR: null print job total=0
D [13/Dec/2017:19:26:19 +0200] [Job 217] PID 1019 (/usr/lib/cups/filter/foomatic-rip) stopped with status 9.
D [13/Dec/2017:19:26:19 +0200] [Job 217] Hint: Try setting the LogLevel to "debug" to find out more.
D [13/Dec/2017:19:26:19 +0200] [Job 217] PID 1020 (/usr/lib/cups/backend/hp) exited with no errors.
D [13/Dec/2017:19:26:19 +0200] [Job 217] End of messages
D [13/Dec/2017:19:26:19 +0200] [Job 217] printer-state=3(idle)
D [13/Dec/2017:19:26:19 +0200] [Job 217] printer-state-message="Filter failed"
D [13/Dec/2017:19:26:19 +0200] [Job 217] printer-state-reasons=none
[
Comment by Dmytro Bagrii (dimich) - Wednesday, 13 December 2017, 18:02 GMT
Well, downgrade both hplip to 3.17.10-2 and hplip-plugin to 3.17.10-1 helped.
hplip-3.17.11-1 really doesn't provide /usr/bin/hpijs.
Comment by Andreas Radke (AndyRTR) - Wednesday, 13 December 2017, 20:29 GMT
All hpijs drivers have been removed. They are declared deprecated already for a long time. Reinstall the printer using a modern driver.
Comment by Dmytro Bagrii (dimich) - Thursday, 14 December 2017, 10:19 GMT
Thanks for the answer. The issue can be closed.

Loading...