[cups-devel] [cups] IPP backend bug

Mellon qmfornews at gmail.com
Sun Sep 6 17:24:09 PDT 2015


Yep, Vadim are right. This is exactly my case. But it is not my only: 
http://ubuntuforums.org/showthread.php?t=2088261&s=e1702a086ff40bbc33154a4711785af6 

I have a low-end wireless router with usb port and embedded simple ipp print 
server like http://sourceforge.net/projects/ippd/ 
So, windows machines can print through this daemon on the router to printer 
pretty flawless.
And a linux machine with CUPS could not print to the printer anything with any 
ppd: nor generic/raw, nor hplip (hpijs/hpcups) .

With generic, raw, hpcups pdds CUPS completes jobs silently, but printer 
doesn't print anything.

With the hpijs ppd CUPS shows the 'Stopped "Filter failed"' status and gives 
following log:



D [06/Sep/2015:15:42:42 +0300] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Active clients"
D [06/Sep/2015:15:42:42 +0300] add_job: requesting-user-name="root"
D [06/Sep/2015:15:42:42 +0300] Adding default job-sheets values "none,none"...
I [06/Sep/2015:15:42:42 +0300] [Job 2106] Adding start banner page "none".
D [06/Sep/2015:15:42:42 +0300] Discarding unused job-created event...
D [06/Sep/2015:15:42:42 +0300] cupsdMarkDirty(---J-)
D [06/Sep/2015:15:42:42 +0300] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Active clients and dirty files"
I [06/Sep/2015:15:42:42 +0300] [Job 2106] Adding end banner page "none".
I [06/Sep/2015:15:42:42 +0300] [Job 2106] File of type application/vnd.cups-
pdf-banner queued by "root".
D [06/Sep/2015:15:42:42 +0300] [Job 2106] hold_until=0
I [06/Sep/2015:15:42:42 +0300] [Job 2106] Queued on "hplj" by "root".
D [06/Sep/2015:15:42:42 +0300] [Job 2106] time-at-processing=1441543362
D [06/Sep/2015:15:42:42 +0300] cupsdMarkDirty(---J-)
D [06/Sep/2015:15:42:42 +0300] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Active clients and dirty files"
D [06/Sep/2015:15:42:42 +0300] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Active clients and dirty files"
D [06/Sep/2015:15:42:42 +0300] Discarding unused printer-state-changed 
event...
D [06/Sep/2015:15:42:42 +0300] [Job 2106] 3 filters for job:
D [06/Sep/2015:15:42:42 +0300] [Job 2106] bannertopdf (application/vnd.cups-
pdf-banner to application/pdf, cost 32)
D [06/Sep/2015:15:42:42 +0300] [Job 2106] pdftopdf (application/pdf to 
application/vnd.cups-pdf, cost 66)
D [06/Sep/2015:15:42:42 +0300] [Job 2106] foomatic-rip (application/vnd.cups-
pdf to printer/hplj, cost 0)
D [06/Sep/2015:15:42:42 +0300] [Job 2106] job-sheets=none,none
D [06/Sep/2015:15:42:42 +0300] [Job 2106] argv[0]="hplj"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] argv[1]="2106"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] argv[2]="root"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] argv[3]="Test Page"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] argv[4]="1"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] argv[5]="job-uuid=urn:uuid:0c5b3967-
a9f3-3541-7e7d-d87000d8181a job-originating-host-name=localhost time-at-
creation=1441543362 time-at-processing=1441543362"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] argv[6]="/var/spool/cups/d02106-001"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] 
envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] 
envp[1]="CUPS_DATADIR=/usr/share/cups"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] 
envp[2]="CUPS_DOCROOT=/usr/share/cups/html"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] 
envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] 
envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] 
envp[5]="CUPS_SERVERBIN=/usr/libexec/cups"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] envp[7]="CUPS_STATEDIR=/run/cups"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] envp[8]="HOME=/var/spool/cups/tmp"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] 
envp[9]="PATH=/usr/libexec/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] 
envp[10]="SERVER_ADMIN=root at z560.lenovo"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] envp[11]="SOFTWARE=CUPS/2.0.4"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] 
envp[12]="TMPDIR=/var/spool/cups/tmp"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] envp[13]="USER=root"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] envp[14]="CUPS_MAX_MESSAGE=2047"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] 
envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] 
envp[16]="CUPS_ENCRYPTION=IfRequested"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] envp[17]="IPP_PORT=631"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] envp[18]="CHARSET=utf-8"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] envp[19]="LANG=en.UTF-8"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] 
envp[20]="PPD=/etc/cups/ppd/hplj.ppd"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] envp[21]="RIP_MAX_CACHE=128m"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] 
envp[22]="CONTENT_TYPE=application/vnd.cups-pdf-banner"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] 
envp[23]="DEVICE_URI=http://192.168.0.1:631/printers/hp_laserjet_1015"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] envp[24]="PRINTER_INFO="
D [06/Sep/2015:15:42:42 +0300] [Job 2106] envp[25]="PRINTER_LOCATION="
D [06/Sep/2015:15:42:42 +0300] [Job 2106] envp[26]="PRINTER=hplj"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] 
envp[27]="PRINTER_STATE_REASONS=none"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] envp[28]="CUPS_FILETYPE=document"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] 
envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-pdf"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] envp[30]="AUTH_I****"
I [06/Sep/2015:15:42:42 +0300] [Job 2106] Started filter 
/usr/libexec/cups/filter/bannertopdf (PID 21962)
I [06/Sep/2015:15:42:42 +0300] [Job 2106] Started filter 
/usr/libexec/cups/filter/pdftopdf (PID 21963)
I [06/Sep/2015:15:42:42 +0300] [Job 2106] Started filter 
/usr/libexec/cups/filter/foomatic-rip (PID 21964)
I [06/Sep/2015:15:42:42 +0300] [Job 2106] Started backend 
/usr/libexec/cups/backend/http (PID 21965)
D [06/Sep/2015:15:42:42 +0300] Discarding unused job-state-changed event...
D [06/Sep/2015:15:42:42 +0300] cupsdSetBusyState: newbusy="Active clients, 
printing jobs, and dirty files", busy="Active clients and dirty files"
D [06/Sep/2015:15:42:42 +0300] cupsdSetBusyState: newbusy="Active clients, 
printing jobs, and dirty files", busy="Active clients, printing jobs, and 
dirty files"
D [06/Sep/2015:15:42:42 +0300] PID 21961 (/usr/libexec/cups/cgi-
bin/printers.cgi) exited with no errors.
D [06/Sep/2015:15:42:42 +0300] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Active clients, printing jobs, and dirty files"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Sending stdin for job...
D [06/Sep/2015:15:42:42 +0300] [Job 2106] update_reasons(attr=0(), 
s="+connecting-to-device")
D [06/Sep/2015:15:42:42 +0300] [Job 2106] STATE: +connecting-to-device
D [06/Sep/2015:15:42:42 +0300] cupsdMarkDirty(---J-)
D [06/Sep/2015:15:42:42 +0300] cupsdSetBusyState: newbusy="Dirty files", 
busy="Printing jobs and dirty files"
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Looking up "192.168.0.1"...
D [06/Sep/2015:15:42:42 +0300] Discarding unused printer-state-changed 
event...
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Calling FindDeviceById(cups-hplj)
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Failed to send: 
org.freedesktop.DBus.Error.ServiceUnknown:The name 
org.freedesktop.ColorManager was not provided by any .service files
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Failed to get find device cups-hplj
D [06/Sep/2015:15:42:42 +0300] [Job 2106] 'CM Color Calibration' Mode in 
SPOOLER-LESS: Off
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Getting input from file 
D [06/Sep/2015:15:42:42 +0300] [Job 2106] foomatic-rip version 1.0.74 
running...
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Parsing PPD file ...
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Added option ColorSpace
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Added option Resolution
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Added option PageSize
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Added option Model
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Added option PrintoutMode
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Added option InputSlot
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Added option Duplex
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Added option Quality
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Added option ImageableArea
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Added option PaperDimension
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Added option Font
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Parameter Summary
D [06/Sep/2015:15:42:42 +0300] [Job 2106] -----------------
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Spooler: cups
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Printer: hplj
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Shell: /bin/bash
D [06/Sep/2015:15:42:42 +0300] [Job 2106] PPD file: /etc/cups/ppd/hplj.ppd
D [06/Sep/2015:15:42:42 +0300] [Job 2106] ATTR file: 
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Printer model: HP LaserJet 1015 
hpijs, 3.15.7
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Job title: Test Page
D [06/Sep/2015:15:42:42 +0300] [Job 2106] File(s) to be printed:
D [06/Sep/2015:15:42:42 +0300] [Job 2106] <STDIN>
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Ghostscript extra search path 
('GS_LIB'): /usr/share/cups/fonts
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Printing system options:
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Pondering option 'job-
uuid=urn:uuid:0c5b3967-a9f3-3541-7e7d-d87000d8181a'
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Unknown option job-
uuid=urn:uuid:0c5b3967-a9f3-3541-7e7d-d87000d8181a.
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Pondering option 'job-originating-
host-name=localhost'
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Unknown option job-originating-host-
name=localhost.
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Pondering option 'time-at-
creation=1441543362'
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Unknown option time-at-
creation=1441543362.
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Pondering option 'time-at-
processing=1441543362'
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Unknown option time-at-
processing=1441543362.
D [06/Sep/2015:15:42:42 +0300] [Job 2106] CM Color Calibration Mode in CUPS: 
Off
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Options from the PPD file:
D [06/Sep/2015:15:42:42 +0300] [Job 2106] 
================================================
D [06/Sep/2015:15:42:42 +0300] [Job 2106] File: <STDIN>
D [06/Sep/2015:15:42:42 +0300] [Job 2106] 
================================================
D [06/Sep/2015:15:42:42 +0300] [Job 2106] PDF template file doesn't have form. 
It's okay.
D [06/Sep/2015:15:42:42 +0300] [Job 2106] PID 21962 
(/usr/libexec/cups/filter/bannertopdf) exited with no errors.
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Filetype: PDF
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Storing temporary files in 
/var/spool/cups/tmp
D [06/Sep/2015:15:42:42 +0300] [Job 2106] PID 21963 
(/usr/libexec/cups/filter/pdftopdf) exited with no errors.
D [06/Sep/2015:15:42:42 +0300] [Job 2106] File contains 1 pages
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Starting renderer with command: gs -
dFirstPage=1  -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -
sIjsServer=hpijs -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -
sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="HP LaserJet" -
dDuplex=false -r300 -
sIjsParams=Quality:Quality=0,Quality:ColorMode=0,Quality:MediaType=0,Quality:PenSet=0,PS:MediaPosition=7 
-dIjsUseOutputFD -sOutputFile=-   /var/spool/cups/tmp/foomatic-jGHP9Q 
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Starting process "kid3" (generation 
1)
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Starting process "kid4" (generation 
2)
D [06/Sep/2015:15:42:42 +0300] [Job 2106] Starting process "renderer" 
(generation 2)
D [06/Sep/2015:15:42:42 +0300] [Job 2106] JCL: -12345X at PJL
D [06/Sep/2015:15:42:42 +0300] [Job 2106] <job data> 
D [06/Sep/2015:15:42:44 +0300] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Dirty files"
D [06/Sep/2015:15:42:44 +0300] cupsdAddCert: Adding certificate for PID 22051
D [06/Sep/2015:15:42:44 +0300] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Active clients and dirty files"
D [06/Sep/2015:15:42:44 +0300] CUPS-Get-Default
D [06/Sep/2015:15:42:44 +0300] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Active clients and dirty files"
D [06/Sep/2015:15:42:44 +0300] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Active clients and dirty files"
D [06/Sep/2015:15:42:44 +0300] Get-Printer-Attributes 
ipp://localhost/printers/hplj
D [06/Sep/2015:15:42:44 +0300] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Active clients and dirty files"
D [06/Sep/2015:15:42:44 +0300] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Active clients and dirty files"
D [06/Sep/2015:15:42:44 +0300] Get-Jobs ipp://localhost:631/printers/hplj
D [06/Sep/2015:15:42:44 +0300] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Active clients and dirty files"
D [06/Sep/2015:15:42:44 +0300] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Active clients and dirty files"
D [06/Sep/2015:15:42:44 +0300] PID 22051 (/usr/libexec/cups/cgi-
bin/printers.cgi) exited with no errors.
D [06/Sep/2015:15:42:44 +0300] cupsdSetBusyState: newbusy="Dirty files", 
busy="Active clients and dirty files"
D [06/Sep/2015:15:42:44 +0300] cupsdSetBusyState: newbusy="Active clients and 
dirty files", busy="Dirty files"
D [06/Sep/2015:15:42:44 +0300] cupsdSetBusyState: newbusy="Dirty files", 
busy="Active clients and dirty files"
D [06/Sep/2015:15:42:46 +0300] [Job 2106] prtGeneralCurrentLocalization type 
is 0, expected 2!
D [06/Sep/2015:15:42:46 +0300] [Job 2106] backendWaitLoop(snmp_fd=6, 
addr=0x55570bf87f38, side_cb=0x55570aea8440)
D [06/Sep/2015:15:42:46 +0300] [Job 2106] Connecting to 192.168.0.1:631
I [06/Sep/2015:15:42:46 +0300] [Job 2106] Connecting to printer.
D [06/Sep/2015:15:42:46 +0300] cupsdMarkDirty(---J-)
D [06/Sep/2015:15:42:46 +0300] cupsdSetBusyState: newbusy="Dirty files", 
busy="Dirty files"
D [06/Sep/2015:15:42:46 +0300] [Job 2106] Set job-printer-state-message to 
"Connecting to printer.", current level=INFO
D [06/Sep/2015:15:42:46 +0300] Discarding unused job-progress event...
D [06/Sep/2015:15:42:46 +0300] Discarding unused printer-state-changed 
event...
D [06/Sep/2015:15:42:46 +0300] [Job 2106] update_reasons(attr=0(), s="-cups-
certificate-error")
D [06/Sep/2015:15:42:46 +0300] [Job 2106] update_reasons(attr=0(), s="-
connecting-to-device")
D [06/Sep/2015:15:42:46 +0300] [Job 2106] STATE: -connecting-to-device
D [06/Sep/2015:15:42:46 +0300] cupsdMarkDirty(---J-)
D [06/Sep/2015:15:42:46 +0300] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Dirty files"
I [06/Sep/2015:15:42:46 +0300] [Job 2106] Connected to printer.
D [06/Sep/2015:15:42:46 +0300] cupsdMarkDirty(---J-)
D [06/Sep/2015:15:42:46 +0300] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Printing jobs and dirty files"
D [06/Sep/2015:15:42:46 +0300] [Job 2106] Set job-printer-state-message to 
"Connected to printer.", current level=INFO
D [06/Sep/2015:15:42:46 +0300] [Job 2106] Connected to 192.168.0.1:631...
D [06/Sep/2015:15:42:46 +0300] Discarding unused job-progress event...
D [06/Sep/2015:15:42:46 +0300] Discarding unused printer-state-changed 
event...
D [06/Sep/2015:15:42:46 +0300] [Job 2106] Getting supported attributes...
D [06/Sep/2015:15:42:46 +0300] [Job 2106] Get-Printer-Attributes: successful-
ok (successful-ok)
D [06/Sep/2015:15:42:46 +0300] [Job 2106] update_reasons(attr=0(), s="+cups-
ipp-conformance-failure-report,cups-ipp-missing-printer-is-accepting-jobs")
D [06/Sep/2015:15:42:46 +0300] [Job 2106] STATE: +cups-ipp-conformance-
failure-report,cups-ipp-missing-printer-is-accepting-jobs
D [06/Sep/2015:15:42:46 +0300] cupsdMarkDirty(P----)
D [06/Sep/2015:15:42:46 +0300] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Printing jobs and dirty files"
D [06/Sep/2015:15:42:46 +0300] cupsdMarkDirty(P----)
D [06/Sep/2015:15:42:46 +0300] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Printing jobs and dirty files"
D [06/Sep/2015:15:42:46 +0300] cupsdMarkDirty(---J-)
D [06/Sep/2015:15:42:46 +0300] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Printing jobs and dirty files"
D [06/Sep/2015:15:42:46 +0300] [Job 2106] update_reasons(attr=0(), s="+cups-
ipp-conformance-failure-report,cups-ipp-missing-printer-state-reasons")
D [06/Sep/2015:15:42:46 +0300] [Job 2106] STATE: +cups-ipp-missing-printer-
state-reasons
D [06/Sep/2015:15:42:46 +0300] cupsdMarkDirty(P----)
D [06/Sep/2015:15:42:46 +0300] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Printing jobs and dirty files"
D [06/Sep/2015:15:42:46 +0300] cupsdMarkDirty(---J-)
D [06/Sep/2015:15:42:46 +0300] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Printing jobs and dirty files"
D [06/Sep/2015:15:42:46 +0300] [Job 2106] document-format-supported (1 values)
D [06/Sep/2015:15:42:46 +0300] Discarding unused printer-state-changed 
event...
D [06/Sep/2015:15:42:46 +0300] [Job 2106] [0] = "application/octet-stream"
D [06/Sep/2015:15:42:46 +0300] [Job 2106] operations-supported (4 values)
D [06/Sep/2015:15:42:46 +0300] [Job 2106] [0] = Print-Job
D [06/Sep/2015:15:42:46 +0300] [Job 2106] [1] = Get-Job-Attributes
D [06/Sep/2015:15:42:46 +0300] [Job 2106] [2] = Get-Jobs
D [06/Sep/2015:15:42:46 +0300] [Job 2106] [3] = Get-Printer-Attributes
D [06/Sep/2015:15:42:46 +0300] [Job 2106] update_reasons(attr=0(), s="+cups-
ipp-conformance-failure-report,cups-ipp-missing-cancel-job")
D [06/Sep/2015:15:42:46 +0300] [Job 2106] STATE: +cups-ipp-missing-cancel-job
D [06/Sep/2015:15:42:46 +0300] cupsdMarkDirty(P----)
D [06/Sep/2015:15:42:46 +0300] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Printing jobs and dirty files"
D [06/Sep/2015:15:42:46 +0300] cupsdMarkDirty(---J-)
D [06/Sep/2015:15:42:46 +0300] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Printing jobs and dirty files"
D [06/Sep/2015:15:42:46 +0300] [Job 2106] update_reasons(attr=0(), s="+cups-
ipp-conformance-failure-report,cups-ipp-missing-validate-job")
D [06/Sep/2015:15:42:46 +0300] [Job 2106] STATE: +cups-ipp-missing-validate-
job
D [06/Sep/2015:15:42:46 +0300] cupsdMarkDirty(P----)
D [06/Sep/2015:15:42:46 +0300] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Printing jobs and dirty files"
D [06/Sep/2015:15:42:46 +0300] cupsdMarkDirty(---J-)
D [06/Sep/2015:15:42:46 +0300] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Printing jobs and dirty files"
D [06/Sep/2015:15:42:46 +0300] [Job 2106] 
final_content_type="application/vnd.cups-pdf", 
document_format="application/octet-stream"
D [06/Sep/2015:15:42:46 +0300] [Job 2106] Print-Job IPP/2.0
D [06/Sep/2015:15:42:46 +0300] [Job 2106] printer-
uri="http://192.168.0.1:631/printers/hp_laserjet_1015"
D [06/Sep/2015:15:42:46 +0300] [Job 2106] requesting-user-name="root"
D [06/Sep/2015:15:42:46 +0300] [Job 2106] job-name="2106 - Test Page"
D [06/Sep/2015:15:42:46 +0300] [Job 2106] document-format="application/octet-
stream"
D [06/Sep/2015:15:42:46 +0300] [Job 2106] Adding all operation/job attributes.
D [06/Sep/2015:15:42:46 +0300] [Job 2106] IPP/2.0 Print-Job #2
D [06/Sep/2015:15:42:46 +0300] [Job 2106] ---- operation-attributes-tag ----
D [06/Sep/2015:15:42:46 +0300] [Job 2106] attributes-charset charset utf-8
D [06/Sep/2015:15:42:46 +0300] [Job 2106] attributes-natural-language 
naturalLanguage en
D [06/Sep/2015:15:42:46 +0300] [Job 2106] printer-uri uri 
http://192.168.0.1:631/printers/hp_laserjet_1015
D [06/Sep/2015:15:42:46 +0300] [Job 2106] requesting-user-name 
nameWithoutLanguage root
D [06/Sep/2015:15:42:46 +0300] [Job 2106] job-name nameWithoutLanguage 2106 - 
Test Page
D [06/Sep/2015:15:42:46 +0300] [Job 2106] document-format mimeMediaType 
application/octet-stream
D [06/Sep/2015:15:42:46 +0300] [Job 2106] document-format mimeMediaType 
application/octet-stream
D [06/Sep/2015:15:42:46 +0300] [Job 2106] ---- job-attributes-tag ----
D [06/Sep/2015:15:42:46 +0300] [Job 2106] job-originating-host-name 
nameWithoutLanguage localhost
D [06/Sep/2015:15:42:46 +0300] [Job 2106] job-uuid uri urn:uuid:0c5b3967-
a9f3-3541-7e7d-d87000d8181a
D [06/Sep/2015:15:42:46 +0300] [Job 2106] ---- end-of-attributes-tag ----
D [06/Sep/2015:15:42:46 +0300] [Job 2106] Sending file using HTTP/1.1 
chunking...
D [06/Sep/2015:15:42:46 +0300] [Job 2106] Print-Job: successful-ok 
(successful-ok)
D [06/Sep/2015:15:42:46 +0300] [Job 2106] Print job accepted - job ID unknown.
D [06/Sep/2015:15:42:46 +0300] [Job 2106] update_reasons(attr=0(), s="+cups-
ipp-conformance-failure-report,cups-ipp-missing-job-id")
D [06/Sep/2015:15:42:46 +0300] [Job 2106] STATE: +cups-ipp-missing-job-id
D [06/Sep/2015:15:42:46 +0300] cupsdMarkDirty(P----)
D [06/Sep/2015:15:42:46 +0300] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Printing jobs and dirty files"
D [06/Sep/2015:15:42:46 +0300] cupsdMarkDirty(---J-)
D [06/Sep/2015:15:42:46 +0300] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Printing jobs and dirty files"
D [06/Sep/2015:15:42:46 +0300] Discarding unused printer-state-changed 
event...
D [06/Sep/2015:15:42:46 +0300] [Job 2106] Get-Printer-Attributes: successful-
ok (successful-ok)
D [06/Sep/2015:15:42:46 +0300] [Job 2106] (monitor) Get-Jobs: server-error-
internal-error (Success)
D [06/Sep/2015:15:42:46 +0300] [Job 2106] (monitor) job-state=pending
D [06/Sep/2015:15:42:46 +0300] [Job 2106] Get-Printer-Attributes: successful-
ok (successful-ok)
D [06/Sep/2015:15:42:46 +0300] [Job 2106] ATTR: auth-info-required=none
D [06/Sep/2015:15:42:46 +0300] load_ppd: Loading /var/cache/cups/hplj.data...
D [06/Sep/2015:15:42:46 +0300] cupsdRegisterPrinter(p=0x55d66ef0f320(hplj))
D [06/Sep/2015:15:42:46 +0300] cupsdMarkDirty(P----)
D [06/Sep/2015:15:42:46 +0300] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Printing jobs and dirty files"
D [06/Sep/2015:15:42:46 +0300] [Job 2106] PID 21965 
(/usr/libexec/cups/backend/http) exited with no errors.
D [06/Sep/2015:15:42:46 +0300] [Job 2106] GPL Ghostscript 9.15: Unrecoverable 
error, exit code 1
D [06/Sep/2015:15:42:46 +0300] [Job 2106] ijs: Can't set parameter 
Quality:Quality=0
D [06/Sep/2015:15:42:46 +0300] [Job 2106] ijs: Can't set parameter 
Quality:ColorMode=0
D [06/Sep/2015:15:42:46 +0300] [Job 2106] ijs: Can't set parameter 
Quality:MediaType=0
D [06/Sep/2015:15:42:46 +0300] [Job 2106] ijs: Can't set parameter 
Quality:PenSet=0
D [06/Sep/2015:15:42:46 +0300] [Job 2106] ijs: Can't set parameter 
PS:MediaPosition=7
D [06/Sep/2015:15:42:46 +0300] [Job 2106] ijs: Can't set parameter 
Quality:Quality=0
D [06/Sep/2015:15:42:46 +0300] [Job 2106] ijs: Can't set parameter 
Quality:ColorMode=0
D [06/Sep/2015:15:42:46 +0300] [Job 2106] ijs: Can't set parameter 
Quality:MediaType=0
D [06/Sep/2015:15:42:46 +0300] [Job 2106] ijs: Can't set parameter 
Quality:PenSet=0
D [06/Sep/2015:15:42:46 +0300] [Job 2106] ijs: Can't set parameter 
PS:MediaPosition=7
D [06/Sep/2015:15:42:46 +0300] [Job 2106] renderer exited with status 1
D [06/Sep/2015:15:42:46 +0300] [Job 2106] Possible error on renderer command 
line or PostScript error. Check options.Kid3 exit status: 3
D [06/Sep/2015:15:42:46 +0300] [Job 2106] PID 21964 
(/usr/libexec/cups/filter/foomatic-rip) stopped with status 9.
D [06/Sep/2015:15:42:46 +0300] Discarding unused job-state-changed event...
E [06/Sep/2015:15:42:46 +0300] [Job 2106] Job stopped due to filter errors; 
please consult the error_log file for details.
D [06/Sep/2015:15:42:46 +0300] cupsdMarkDirty(---J-)
D [06/Sep/2015:15:42:46 +0300] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Printing jobs and dirty files"
D [06/Sep/2015:15:42:46 +0300] cupsdSetBusyState: newbusy="Printing jobs and 
dirty files", busy="Printing jobs and dirty files"
D [06/Sep/2015:15:42:46 +0300] Discarding unused printer-state-changed 
event...
D [06/Sep/2015:15:42:46 +0300] [Job 2106] The following messages were recorded 
from 15:42:42 to 15:42:46
D [06/Sep/2015:15:42:46 +0300] [Job 2106] op='+', new_reasons=1, 
state_reasons=1
D [06/Sep/2015:15:42:46 +0300] [Job 2106] hrDeviceDesc="Unknown"
D [06/Sep/2015:15:42:46 +0300] [Job 2106] op='-', new_reasons=1, 
state_reasons=2
D [06/Sep/2015:15:42:46 +0300] [Job 2106] op='-', new_reasons=1, 
state_reasons=2
D [06/Sep/2015:15:42:46 +0300] [Job 2106] op='+', new_reasons=2, 
state_reasons=1
D [06/Sep/2015:15:42:46 +0300] [Job 2106] op='+', new_reasons=2, 
state_reasons=3
D [06/Sep/2015:15:42:46 +0300] [Job 2106] op='+', new_reasons=2, 
state_reasons=4
D [06/Sep/2015:15:42:46 +0300] [Job 2106] op='+', new_reasons=2, 
state_reasons=5
D [06/Sep/2015:15:42:46 +0300] [Job 2106] op='+', new_reasons=2, 
state_reasons=6
D [06/Sep/2015:15:42:46 +0300] [Job 2106] End of messages
D [06/Sep/2015:15:42:46 +0300] [Job 2106] printer-state=3(idle)
D [06/Sep/2015:15:42:46 +0300] [Job 2106] printer-state-message="Filter 
failed"
D [06/Sep/2015:15:42:46 +0300] [Job 2106] printer-state-reasons=cups-ipp-
conformance-failure-report,cups-ipp-missing-printer-is-accepting-jobs,cups-
ipp-missing-printer-state-reasons,cups-ipp-missing-cancel-job,cups-ipp-
missing-validate-job,cups-ipp-missing-job-id
D [06/Sep/2015:15:42:47 +0300] [Job 2106] Unloading...



More information about the cups-devel mailing list