[cups-devel] [UNKN] STR #4716: Generic PCL3 ppd not working with HP Deskjet 1112 printer

Mon Sep 14 07:00:21 PDT 2015

-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA512

DO NOT REPLY TO THIS MESSAGE.  INSTEAD, POST ANY RESPONSES TO THE LINK BELOW.

[STR New]

d [14/Sep/2015:13:52:09 +0000] cupsdDeleteProfile(profile="(null)")
I [14/Sep/2015:13:52:09 +0000] Saving job.cache...
d [14/Sep/2015:13:52:09 +0000] cupsdStopSelect()
I [14/Sep/2015:13:52:09 +0000] Listening to [v1.::1]:631 (IPv6)
I [14/Sep/2015:13:52:09 +0000] Listening to 127.0.0.1:631 (IPv4)
I [14/Sep/2015:13:52:09 +0000] Listening to /var/run/cups/cups.sock
(Domain)
I [14/Sep/2015:13:52:09 +0000] Remote access is disabled.
D [14/Sep/2015:13:52:09 +0000] Added auto ServerAlias beaglebone
I [14/Sep/2015:13:52:09 +0000] Loaded configuration file
"/etc/cups/cupsd.conf"
I [14/Sep/2015:13:52:09 +0000] Using default TempDir of
/var/spool/cups/tmp...
I [14/Sep/2015:13:52:09 +0000] Configured for up to 100 clients.
I [14/Sep/2015:13:52:09 +0000] Allowing up to 100 client connections per
host.
I [14/Sep/2015:13:52:09 +0000] Using policy "default" as the default.
I [14/Sep/2015:13:52:09 +0000] Full reload is required.
I [14/Sep/2015:13:52:09 +0000] Loaded MIME database from
"/usr/share/cups/mime" and "/etc/cups": 39 types, 56 filters...
D [14/Sep/2015:13:52:09 +0000] Loading printer HP...
D [14/Sep/2015:13:52:09 +0000] load_ppd: Loading /var/cache/cups/HP.data...
D [14/Sep/2015:13:52:09 +0000] Calling DeleteDevice(cups-HP)
D [14/Sep/2015:13:52:09 +0000] failed to DeleteDevice:
org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not
match expected type `(o)'
D [14/Sep/2015:13:52:09 +0000] Using profile id of HP-Gray..
D [14/Sep/2015:13:52:09 +0000] Calling CreateProfile(HP-Gray..,temp)
D [14/Sep/2015:13:52:09 +0000] created profile
/org/freedesktop/ColorManager/profiles/HP_Gray__
D [14/Sep/2015:13:52:09 +0000] Using profile id of HP-RGB..
D [14/Sep/2015:13:52:09 +0000] Calling CreateProfile(HP-RGB..,temp)
D [14/Sep/2015:13:52:09 +0000] created profile
/org/freedesktop/ColorManager/profiles/HP_RGB__
I [14/Sep/2015:13:52:09 +0000] Registering ICC color profiles for "HP"
D [14/Sep/2015:13:52:09 +0000] Calling CreateDevice(cups-HP,temp)
D [14/Sep/2015:13:52:09 +0000] created device
/org/freedesktop/ColorManager/devices/cups_HP
D [14/Sep/2015:13:52:09 +0000] Calling
/org/freedesktop/ColorManager/devices/cups_HP:AddProfile(/org/freedesktop/ColorManager/profiles/HP_Gray__)
[soft]
D [14/Sep/2015:13:52:09 +0000] Calling
/org/freedesktop/ColorManager/devices/cups_HP:AddProfile(/org/freedesktop/ColorManager/profiles/HP_RGB__)
[soft]
D [14/Sep/2015:13:52:09 +0000] cupsdRegisterPrinter(p=0xb6fe1110(HP))
I [14/Sep/2015:13:52:09 +0000] Loading job cache file
"/var/cache/cups/job.cache"...
D [14/Sep/2015:13:52:09 +0000] [Job 24] Loading from cache...
I [14/Sep/2015:13:52:09 +0000] Full reload complete.
D [14/Sep/2015:13:52:09 +0000] cupsdCleanFiles(path="/var/spool/cups/tmp",
pattern="(null)")
I [14/Sep/2015:13:52:09 +0000] Cleaning out old files in
"/var/spool/cups/tmp"...
D [14/Sep/2015:13:52:09 +0000] Removed "/var/spool/cups/tmp/gs_gFtqyr"...
D [14/Sep/2015:13:52:09 +0000] Removed "/var/spool/cups/tmp/gs_0ucmiP"...
D [14/Sep/2015:13:52:09 +0000] Removed "/var/spool/cups/tmp/gs_rEjMog"...
D [14/Sep/2015:13:52:09 +0000] Removed
"/var/spool/cups/tmp/foomatic-RDWKIP"...
D [14/Sep/2015:13:52:09 +0000] Removed "/var/spool/cups/tmp/gs_2yMCsh"...
D [14/Sep/2015:13:52:09 +0000] Removed "/var/spool/cups/tmp/gs_FLeaXv"...
D [14/Sep/2015:13:52:09 +0000] Removed
"/var/spool/cups/tmp/foomatic-yIOFYe"...
D [14/Sep/2015:13:52:09 +0000] cupsdCleanFiles(path="/var/cache/cups",
pattern="*.ipp")
I [14/Sep/2015:13:52:09 +0000] Cleaning out old files in
"/var/cache/cups"...
I [14/Sep/2015:13:52:09 +0000] Listening to [v1.::1]:631 on fd 9...
I [14/Sep/2015:13:52:09 +0000] Listening to 127.0.0.1:631 on fd 10...
I [14/Sep/2015:13:52:09 +0000] Listening to /var/run/cups/cups.sock:631 on
fd 11...
I [14/Sep/2015:13:52:09 +0000] Resuming new connection processing...
D [14/Sep/2015:13:52:09 +0000] Avahi client started
D [14/Sep/2015:13:52:09 +0000] cupsdRegisterPrinter(p=0xb6fe1110(HP))
D [14/Sep/2015:13:52:09 +0000] cupsdSetBusyState: newbusy="Not busy",
busy="Not busy"
D [14/Sep/2015:13:52:09 +0000] Discarding unused server-started event...
D [14/Sep/2015:13:52:10 +0000] Report: clients=0
D [14/Sep/2015:13:52:10 +0000] Report: jobs=1
D [14/Sep/2015:13:52:10 +0000] Report: jobs-active=0
D [14/Sep/2015:13:52:10 +0000] Report: printers=1
D [14/Sep/2015:13:52:10 +0000] Report: printers-implicit=0
D [14/Sep/2015:13:52:10 +0000] Report: stringpool-string-count=5636
D [14/Sep/2015:13:52:10 +0000] Report: stringpool-alloc-bytes=9136
D [14/Sep/2015:13:52:10 +0000] Report: stringpool-total-bytes=104984
D [14/Sep/2015:13:52:25 +0000] cupsdAcceptClient: 16 from localhost
(Domain)
D [14/Sep/2015:13:52:25 +0000] cupsdReadClient: 16 POST / HTTP/1.1
D [14/Sep/2015:13:52:25 +0000] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [14/Sep/2015:13:52:25 +0000] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2015:13:52:25 +0000] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [14/Sep/2015:13:52:25 +0000] CUPS-Get-Printers
D [14/Sep/2015:13:52:25 +0000] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost
D [14/Sep/2015:13:52:25 +0000] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [14/Sep/2015:13:52:25 +0000] cupsdReadClient: 16 POST / HTTP/1.1
D [14/Sep/2015:13:52:25 +0000] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [14/Sep/2015:13:52:25 +0000] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2015:13:52:25 +0000] cupsdReadClient: 16 1.1 CUPS-Get-Default 1
D [14/Sep/2015:13:52:25 +0000] CUPS-Get-Default
D [14/Sep/2015:13:52:25 +0000] CUPS-Get-Default client-error-not-found: No
default printer.
D [14/Sep/2015:13:52:25 +0000] Returning IPP client-error-not-found for
CUPS-Get-Default (no URI) from localhost
D [14/Sep/2015:13:52:25 +0000] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [14/Sep/2015:13:52:25 +0000] cupsdReadClient: 16 POST / HTTP/1.1
D [14/Sep/2015:13:52:25 +0000] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [14/Sep/2015:13:52:25 +0000] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2015:13:52:25 +0000] cupsdAcceptClient: 17 from localhost
(Domain)
D [14/Sep/2015:13:52:25 +0000] cupsdReadClient: 17 WAITING Closing on EOF
D [14/Sep/2015:13:52:25 +0000] cupsdCloseClient: 17
D [14/Sep/2015:13:52:25 +0000] cupsdSetBusyState: newbusy="Active clients",
busy="Active clients"
D [14/Sep/2015:13:52:25 +0000] cupsdReadClient: 16 1.1 CUPS-Get-Classes 1
D [14/Sep/2015:13:52:25 +0000] CUPS-Get-Classes
D [14/Sep/2015:13:52:25 +0000] Returning IPP successful-ok for
CUPS-Get-Classes (no URI) from localhost
D [14/Sep/2015:13:52:25 +0000] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [14/Sep/2015:13:52:25 +0000] cupsdReadClient: 16 POST / HTTP/1.1
D [14/Sep/2015:13:52:25 +0000] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [14/Sep/2015:13:52:25 +0000] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2015:13:52:25 +0000] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [14/Sep/2015:13:52:25 +0000] CUPS-Get-Printers
D [14/Sep/2015:13:52:25 +0000] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost
D [14/Sep/2015:13:52:25 +0000] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [14/Sep/2015:13:52:25 +0000] cupsdReadClient: 16 POST / HTTP/1.1
D [14/Sep/2015:13:52:25 +0000] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [14/Sep/2015:13:52:25 +0000] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2015:13:52:25 +0000] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [14/Sep/2015:13:52:25 +0000] CUPS-Get-Printers
D [14/Sep/2015:13:52:25 +0000] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost
D [14/Sep/2015:13:52:25 +0000] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [14/Sep/2015:13:52:25 +0000] cupsdReadClient: 16 POST / HTTP/1.1
D [14/Sep/2015:13:52:25 +0000] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [14/Sep/2015:13:52:25 +0000] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2015:13:52:25 +0000] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [14/Sep/2015:13:52:25 +0000] CUPS-Get-Printers
D [14/Sep/2015:13:52:25 +0000] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost
D [14/Sep/2015:13:52:25 +0000] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [14/Sep/2015:13:52:25 +0000] cupsdReadClient: 16 POST / HTTP/1.1
D [14/Sep/2015:13:52:25 +0000] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [14/Sep/2015:13:52:25 +0000] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2015:13:52:25 +0000] cupsdReadClient: 16 1.1 Get-Jobs 1
D [14/Sep/2015:13:52:25 +0000] Get-Jobs ipp://localhost/
D [14/Sep/2015:13:52:25 +0000] Returning IPP successful-ok for Get-Jobs
(ipp://localhost/) from localhost
D [14/Sep/2015:13:52:25 +0000] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [14/Sep/2015:13:52:25 +0000] cupsdReadClient: 16 WAITING Closing on EOF
D [14/Sep/2015:13:52:25 +0000] cupsdCloseClient: 16
D [14/Sep/2015:13:52:25 +0000] cupsdSetBusyState: newbusy="Not busy",
busy="Not busy"
D [14/Sep/2015:13:52:36 +0000] cupsdAcceptClient: 16 from localhost
(Domain)
D [14/Sep/2015:13:52:36 +0000] cupsdReadClient: 16 POST / HTTP/1.1
D [14/Sep/2015:13:52:36 +0000] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [14/Sep/2015:13:52:36 +0000] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2015:13:52:36 +0000] cupsdReadClient: 16 1.1
Get-Printer-Attributes 1
D [14/Sep/2015:13:52:36 +0000] Get-Printer-Attributes
ipp://localhost:631/printers/HP
D [14/Sep/2015:13:52:36 +0000] Returning IPP successful-ok for
Get-Printer-Attributes (ipp://localhost:631/printers/HP) from localhost
D [14/Sep/2015:13:52:36 +0000] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [14/Sep/2015:13:52:36 +0000] cupsdReadClient: 16 POST /printers/HP
HTTP/1.1
D [14/Sep/2015:13:52:36 +0000] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [14/Sep/2015:13:52:36 +0000] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2015:13:52:36 +0000] cupsdReadClient: 16 1.1 Create-Job 1
D [14/Sep/2015:13:52:36 +0000] Create-Job ipp://localhost:631/printers/HP
D [14/Sep/2015:13:52:36 +0000] cupsdMarkDirty(----J-)
D [14/Sep/2015:13:52:36 +0000] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Active clients"
D [14/Sep/2015:13:52:36 +0000] add_job: requesting-user-name="root"
I [14/Sep/2015:13:52:36 +0000] [Job 25] Adding start banner page "none".
D [14/Sep/2015:13:52:36 +0000] Discarding unused job-created event...
I [14/Sep/2015:13:52:36 +0000] [Job 25] Queued on "HP" by "root".
D [14/Sep/2015:13:52:36 +0000] Returning IPP successful-ok for Create-Job
(ipp://localhost:631/printers/HP) from localhost
D [14/Sep/2015:13:52:36 +0000] cupsdSetBusyState: newbusy="Dirty files",
busy="Active clients and dirty files"
D [14/Sep/2015:13:52:36 +0000] cupsdReadClient: 16 POST /printers/HP
HTTP/1.1
D [14/Sep/2015:13:52:36 +0000] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Dirty files"
D [14/Sep/2015:13:52:36 +0000] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2015:13:52:36 +0000] cupsdReadClient: 16 1.1 Send-Document 1
D [14/Sep/2015:13:52:36 +0000] Send-Document
ipp://localhost:631/printers/HP
D [14/Sep/2015:13:52:36 +0000] cupsdIsAuthorized:
requesting-user-name="root"
D [14/Sep/2015:13:52:36 +0000] [Job 25] Auto-typing file...
D [14/Sep/2015:13:52:36 +0000] [Job 25] Request file type is text/plain.
D [14/Sep/2015:13:52:36 +0000] cupsdMarkDirty(----J-)
D [14/Sep/2015:13:52:36 +0000] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Active clients and dirty files"
I [14/Sep/2015:13:52:36 +0000] [Job 25] File of type text/plain queued by
"root".
I [14/Sep/2015:13:52:36 +0000] [Job 25] Adding end banner page "none".
D [14/Sep/2015:13:52:36 +0000] cupsdMarkDirty(----J-)
D [14/Sep/2015:13:52:36 +0000] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Active clients and dirty files"
D [14/Sep/2015:13:52:36 +0000] cupsdMarkDirty(----J-)
D [14/Sep/2015:13:52:36 +0000] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Active clients and dirty files"
D [14/Sep/2015:13:52:36 +0000] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Active clients and dirty files"
D [14/Sep/2015:13:52:36 +0000] Discarding unused printer-state-changed
event...
D [14/Sep/2015:13:52:36 +0000] [Job 25] job-sheets=none,none
D [14/Sep/2015:13:52:36 +0000] [Job 25] argv[0]="HP"
D [14/Sep/2015:13:52:36 +0000] [Job 25] argv[1]="25"
D [14/Sep/2015:13:52:36 +0000] [Job 25] argv[2]="root"
D [14/Sep/2015:13:52:36 +0000] [Job 25] argv[3]="test.txt"
D [14/Sep/2015:13:52:36 +0000] [Job 25] argv[4]="1"
D [14/Sep/2015:13:52:36 +0000] [Job 25] argv[5]="finishings=3 number-up=1
job-uuid=urn:uuid:294e9b3c-91de-317b-697f-8ef7f0512408
job-originating-host-name=localhost time-at-creation=1442238756
time-at-processing=1442238756"
D [14/Sep/2015:13:52:36 +0000] [Job 25]
argv[6]="/var/spool/cups/d00025-001"
D [14/Sep/2015:13:52:36 +0000] [Job 25]
envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [14/Sep/2015:13:52:36 +0000] [Job 25]
envp[1]="CUPS_DATADIR=/usr/share/cups"
D [14/Sep/2015:13:52:36 +0000] [Job 25]
envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [14/Sep/2015:13:52:36 +0000] [Job 25]
envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [14/Sep/2015:13:52:36 +0000] [Job 25]
envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [14/Sep/2015:13:52:36 +0000] [Job 25]
envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [14/Sep/2015:13:52:36 +0000] [Job 25] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [14/Sep/2015:13:52:36 +0000] [Job 25]
envp[7]="CUPS_STATEDIR=/var/run/cups"
D [14/Sep/2015:13:52:36 +0000] [Job 25] envp[8]="HOME=/var/spool/cups/tmp"
D [14/Sep/2015:13:52:36 +0000] [Job 25]
envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [14/Sep/2015:13:52:36 +0000] [Job 25]
envp[10]="SERVER_ADMIN=root at beaglebone"
D [14/Sep/2015:13:52:36 +0000] [Job 25] envp[11]="SOFTWARE=CUPS/1.5.3"
D [14/Sep/2015:13:52:36 +0000] [Job 25]
envp[12]="TMPDIR=/var/spool/cups/tmp"
D [14/Sep/2015:13:52:36 +0000] [Job 25] envp[13]="TZ=Etc/UTC"
D [14/Sep/2015:13:52:36 +0000] [Job 25] envp[14]="USER=root"
D [14/Sep/2015:13:52:36 +0000] [Job 25]
envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [14/Sep/2015:13:52:36 +0000] [Job 25]
envp[16]="CUPS_ENCRYPTION=IfRequested"
D [14/Sep/2015:13:52:36 +0000] [Job 25] envp[17]="IPP_PORT=631"
D [14/Sep/2015:13:52:36 +0000] [Job 25] envp[18]="CHARSET=utf-8"
D [14/Sep/2015:13:52:36 +0000] [Job 25] envp[19]="LANG=en_US.UTF-8"
D [14/Sep/2015:13:52:36 +0000] [Job 25] envp[20]="PPD=/etc/cups/ppd/HP.ppd"
D [14/Sep/2015:13:52:36 +0000] [Job 25] envp[21]="RIP_MAX_CACHE=128m"
D [14/Sep/2015:13:52:36 +0000] [Job 25] envp[22]="CONTENT_TYPE=text/plain"
D [14/Sep/2015:13:52:36 +0000] [Job 25]
envp[23]="DEVICE_URI=usb://HP/DeskJet%201110%20series?serial=CN55D1N02S065W"
D [14/Sep/2015:13:52:36 +0000] [Job 25] envp[24]="PRINTER_INFO=HP"
D [14/Sep/2015:13:52:36 +0000] [Job 25] envp[25]="PRINTER_LOCATION="
D [14/Sep/2015:13:52:36 +0000] [Job 25] envp[26]="PRINTER=HP"
D [14/Sep/2015:13:52:36 +0000] [Job 25]
envp[27]="PRINTER_STATE_REASONS=none"
D [14/Sep/2015:13:52:36 +0000] [Job 25] envp[28]="CUPS_FILETYPE=document"
D [14/Sep/2015:13:52:36 +0000] [Job 25]
envp[29]="FINAL_CONTENT_TYPE=printer/HP"
D [14/Sep/2015:13:52:36 +0000] [Job 25] envp[30]="AUTH_I****"
I [14/Sep/2015:13:52:36 +0000] [Job 25] Started filter
/usr/lib/cups/filter/texttopdf (PID 24887)
I [14/Sep/2015:13:52:36 +0000] [Job 25] Started filter
/usr/lib/cups/filter/pdftopdf (PID 24888)
I [14/Sep/2015:13:52:36 +0000] [Job 25] Started filter
/usr/lib/cups/filter/foomatic-rip (PID 24889)
I [14/Sep/2015:13:52:36 +0000] [Job 25] Started backend
/usr/lib/cups/backend/usb (PID 24890)
D [14/Sep/2015:13:52:36 +0000] Discarding unused job-state-changed event...
D [14/Sep/2015:13:52:36 +0000] Returning IPP successful-ok for
Send-Document (ipp://localhost:631/printers/HP) from localhost
D [14/Sep/2015:13:52:36 +0000] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Active clients and dirty files"
D [14/Sep/2015:13:52:36 +0000] cupsdReadClient: 16 WAITING Closing on EOF
D [14/Sep/2015:13:52:36 +0000] cupsdCloseClient: 16
D [14/Sep/2015:13:52:36 +0000] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files"
D [14/Sep/2015:13:52:36 +0000] [Job 25] Getting input from file 
D [14/Sep/2015:13:52:36 +0000] [Job 25] foomatic-rip version 4.0.17.256
running...
D [14/Sep/2015:13:52:36 +0000] [Job 25] Parsing PPD file ...
D [14/Sep/2015:13:52:36 +0000] [Job 25] Added option ColorSpace
D [14/Sep/2015:13:52:36 +0000] [Job 25] Added option PageSize
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option ImageableArea
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option PaperDimension
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option Resolution
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option ColorModel
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option Quality
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option MediaType
D [14/Sep/2015:13:52:37 +0000] [Job 25] Printing on printer with URI:
usb://HP/DeskJet%201110%20series?serial=CN55D1N02S065W
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option InputSlot
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option Manual
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option Duplex
D [14/Sep/2015:13:52:37 +0000] [Job 25] libusb_get_device_list=3
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option IntensityRendering
D [14/Sep/2015:13:52:37 +0000] [Job 25] STATE: +connecting-to-device
D [14/Sep/2015:13:52:37 +0000] Discarding unused printer-state-changed
event...
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option RasterGraphicsQuality
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option Postcards
D [14/Sep/2015:13:52:37 +0000] [Job 25] STATE: -connecting-to-device
D [14/Sep/2015:13:52:37 +0000] Discarding unused printer-state-changed
event...
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option Model
D [14/Sep/2015:13:52:37 +0000] [Job 25] Device protocol: 2
I [14/Sep/2015:13:52:37 +0000] [Job 25] Sending data to printer.
D [14/Sep/2015:13:52:37 +0000] [Job 25] Set job-printer-state-message to
"Sending data to printer.", current level=INFO
D [14/Sep/2015:13:52:37 +0000] Discarding unused job-progress event...
D [14/Sep/2015:13:52:37 +0000] Discarding unused printer-state-changed
event...
D [14/Sep/2015:13:52:37 +0000] [Job 25] Page = 612x792; 18,36 to 594,756
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option DitherPPI
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option MemLimit
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option ConfigureEveryPage
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option CompressionMethod
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option LeadingEdge
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option BlackLevels
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option CMYLevels
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option Depletion
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option Passes
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option OnlyCRD
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option PCL3GUI
D [14/Sep/2015:13:52:37 +0000] [Job 25] Added option Font
D [14/Sep/2015:13:52:37 +0000] [Job 25] 
D [14/Sep/2015:13:52:37 +0000] [Job 25] Parameter Summary
D [14/Sep/2015:13:52:37 +0000] [Job 25] -----------------
D [14/Sep/2015:13:52:37 +0000] [Job 25] 
D [14/Sep/2015:13:52:37 +0000] [Job 25] Spooler: cups
D [14/Sep/2015:13:52:37 +0000] [Job 25] Printer: HP
D [14/Sep/2015:13:52:37 +0000] [Job 25] Shell: /bin/bash
D [14/Sep/2015:13:52:37 +0000] [Job 25] PPD file: /etc/cups/ppd/HP.ppd
D [14/Sep/2015:13:52:37 +0000] [Job 25] ATTR file: 
D [14/Sep/2015:13:52:37 +0000] [Job 25] Printer model: Generic PCL 3
Printer Foomatic/pcl3 (recommended)
D [14/Sep/2015:13:52:37 +0000] [Job 25] Job title: test.txt
D [14/Sep/2015:13:52:37 +0000] [Job 25] File(s) to be printed:
D [14/Sep/2015:13:52:37 +0000] [Job 25] <STDIN>
D [14/Sep/2015:13:52:37 +0000] [Job 25] 
D [14/Sep/2015:13:52:37 +0000] [Job 25] Ghostscript extra search path
('GS_LIB'): /usr/share/cups/fonts
D [14/Sep/2015:13:52:37 +0000] [Job 25] Printing system options:
D [14/Sep/2015:13:52:37 +0000] [Job 25] Pondering option 'finishings=3'
D [14/Sep/2015:13:52:37 +0000] [Job 25] Unknown option finishings=3.
D [14/Sep/2015:13:52:37 +0000] [Job 25] Pondering option 'number-up=1'
D [14/Sep/2015:13:52:37 +0000] [Job 25] Unknown option number-up=1.
D [14/Sep/2015:13:52:37 +0000] [Job 25] Pondering option
'job-uuid=urn:uuid:294e9b3c-91de-317b-697f-8ef7f0512408'
D [14/Sep/2015:13:52:37 +0000] [Job 25] Unknown option
job-uuid=urn:uuid:294e9b3c-91de-317b-697f-8ef7f0512408.
D [14/Sep/2015:13:52:37 +0000] [Job 25] Pondering option
'job-originating-host-name=localhost'
D [14/Sep/2015:13:52:37 +0000] [Job 25] Unknown option
job-originating-host-name=localhost.
D [14/Sep/2015:13:52:37 +0000] [Job 25] Pondering option
'time-at-creation=1442238756'
D [14/Sep/2015:13:52:37 +0000] [Job 25] Unknown option
time-at-creation=1442238756.
D [14/Sep/2015:13:52:37 +0000] [Job 25] Pondering option
'time-at-processing=1442238756'
D [14/Sep/2015:13:52:37 +0000] [Job 25] Unknown option
time-at-processing=1442238756.
D [14/Sep/2015:13:52:37 +0000] [Job 25] Options from the PPD file:
D [14/Sep/2015:13:52:37 +0000] [Job 25] 
D [14/Sep/2015:13:52:37 +0000] [Job 25]
================================================
D [14/Sep/2015:13:52:37 +0000] [Job 25] 
D [14/Sep/2015:13:52:37 +0000] [Job 25] File: <STDIN>
D [14/Sep/2015:13:52:37 +0000] [Job 25] 
D [14/Sep/2015:13:52:37 +0000] [Job 25]
================================================
D [14/Sep/2015:13:52:37 +0000] [Job 25] 
D [14/Sep/2015:13:52:37 +0000] PID 24887 (/usr/lib/cups/filter/texttopdf)
exited with no errors.
D [14/Sep/2015:13:52:37 +0000] [Job 25] Filetype: PDF
D [14/Sep/2015:13:52:37 +0000] [Job 25] Storing temporary files in
/var/spool/cups/tmp
D [14/Sep/2015:13:52:37 +0000] PID 24888 (/usr/lib/cups/filter/pdftopdf)
exited with no errors.
D [14/Sep/2015:13:52:37 +0000] [Job 25] File contains 1 pages
D [14/Sep/2015:13:52:37 +0000] [Job 25] Starting renderer with command: gs
- -dFirstPage=1  -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -dNOINTERPOLATE
- -sDEVICE=pcl3 -sSubdevice=unspec -r300x300 -dDEVICEWIDTHPOINTS=612
- -dDEVICEHEIGHTPOINTS=792 -sColorModel=CMY+K -sPrintQuality=0 -sMedium=0
- -dCompressionMethod=0 -dMediaPosition=1 -dDuplex=false -dLeadingEdge=2
- -dBlackLevels=2 -dCMYLevels=2 -sIntensityRendering=printer
- -dRasterGraphicsQuality=2 -sPJLLanguage=PCL3GUI -dDITHERPPI=60
- -dMaxBitmap=8388608 -sOutputFile=-   /var/spool/cups/tmp/foomatic-Okm1Ep 
D [14/Sep/2015:13:52:37 +0000] [Job 25] Starting process "kid3" (generation
1)
D [14/Sep/2015:13:52:37 +0000] [Job 25] Starting process "kid4" (generation
2)
D [14/Sep/2015:13:52:37 +0000] [Job 25] Starting process "renderer"
(generation 2)
D [14/Sep/2015:13:52:37 +0000] [Job 25] JCL: %-12345X at PJL
D [14/Sep/2015:13:52:37 +0000] [Job 25] <job data> 
D [14/Sep/2015:13:52:37 +0000] [Job 25] 
D [14/Sep/2015:13:52:38 +0000] [Job 25] Read 8192 bytes of print data...
D [14/Sep/2015:13:52:38 +0000] [Job 25] Wrote 8192 bytes of print data...
D [14/Sep/2015:13:52:38 +0000] [Job 25] renderer exited with status 0
D [14/Sep/2015:13:52:38 +0000] [Job 25] Read 3474 bytes of print data...
D [14/Sep/2015:13:52:38 +0000] [Job 25] Wrote 3474 bytes of print data...
D [14/Sep/2015:13:52:38 +0000] [Job 25] kid4 exited with status 0
D [14/Sep/2015:13:52:38 +0000] [Job 25] kid3 finished
D [14/Sep/2015:13:52:38 +0000] [Job 25] Kid3 exit status: 0
D [14/Sep/2015:13:52:38 +0000] [Job 25] 
D [14/Sep/2015:13:52:38 +0000] [Job 25] Closing foomatic-rip.
D [14/Sep/2015:13:52:38 +0000] [Job 25] Sent 11666 bytes...
D [14/Sep/2015:13:52:38 +0000] [Job 25] Waiting for read thread to exit...
D [14/Sep/2015:13:52:38 +0000] PID 24889
(/usr/lib/cups/filter/foomatic-rip) exited with no errors.
D [14/Sep/2015:13:52:38 +0000] PID 24890 (/usr/lib/cups/backend/usb) exited
with no errors.
D [14/Sep/2015:13:52:38 +0000] Discarding unused job-completed event...
I [14/Sep/2015:13:52:38 +0000] [Job 25] Job completed.
D [14/Sep/2015:13:52:38 +0000] cupsdMarkDirty(----J-)
D [14/Sep/2015:13:52:38 +0000] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files"
D [14/Sep/2015:13:52:38 +0000] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files"
D [14/Sep/2015:13:52:38 +0000] Discarding unused printer-state-changed
event...
D [14/Sep/2015:13:52:39 +0000] [Job 25] Unloading...
D [14/Sep/2015:13:52:40 +0000] cupsdAcceptClient: 16 from localhost
(Domain)
D [14/Sep/2015:13:52:40 +0000] cupsdReadClient: 16 POST / HTTP/1.1
D [14/Sep/2015:13:52:40 +0000] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Printing jobs and dirty files"
D [14/Sep/2015:13:52:40 +0000] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2015:13:52:40 +0000] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [14/Sep/2015:13:52:40 +0000] CUPS-Get-Printers
D [14/Sep/2015:13:52:40 +0000] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost
D [14/Sep/2015:13:52:40 +0000] cupsdSetBusyState: newbusy="Dirty files",
busy="Active clients and dirty files"
D [14/Sep/2015:13:52:40 +0000] cupsdReadClient: 16 POST / HTTP/1.1
D [14/Sep/2015:13:52:40 +0000] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Dirty files"
D [14/Sep/2015:13:52:40 +0000] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2015:13:52:40 +0000] cupsdReadClient: 16 1.1 CUPS-Get-Default 1
D [14/Sep/2015:13:52:40 +0000] CUPS-Get-Default
D [14/Sep/2015:13:52:40 +0000] CUPS-Get-Default client-error-not-found: No
default printer.
D [14/Sep/2015:13:52:40 +0000] Returning IPP client-error-not-found for
CUPS-Get-Default (no URI) from localhost
D [14/Sep/2015:13:52:40 +0000] cupsdSetBusyState: newbusy="Dirty files",
busy="Active clients and dirty files"
D [14/Sep/2015:13:52:40 +0000] cupsdReadClient: 16 POST / HTTP/1.1
D [14/Sep/2015:13:52:40 +0000] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Dirty files"
D [14/Sep/2015:13:52:40 +0000] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2015:13:52:40 +0000] cupsdAcceptClient: 17 from localhost
(Domain)
D [14/Sep/2015:13:52:40 +0000] cupsdReadClient: 17 WAITING Closing on EOF
D [14/Sep/2015:13:52:40 +0000] cupsdCloseClient: 17
D [14/Sep/2015:13:52:40 +0000] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Active clients and dirty files"
D [14/Sep/2015:13:52:40 +0000] cupsdReadClient: 16 1.1 CUPS-Get-Classes 1
D [14/Sep/2015:13:52:40 +0000] CUPS-Get-Classes
D [14/Sep/2015:13:52:40 +0000] Returning IPP successful-ok for
CUPS-Get-Classes (no URI) from localhost
D [14/Sep/2015:13:52:40 +0000] cupsdSetBusyState: newbusy="Dirty files",
busy="Active clients and dirty files"
D [14/Sep/2015:13:52:40 +0000] cupsdReadClient: 16 POST / HTTP/1.1
D [14/Sep/2015:13:52:40 +0000] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Dirty files"
D [14/Sep/2015:13:52:40 +0000] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2015:13:52:40 +0000] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [14/Sep/2015:13:52:40 +0000] CUPS-Get-Printers
D [14/Sep/2015:13:52:40 +0000] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost
D [14/Sep/2015:13:52:40 +0000] cupsdSetBusyState: newbusy="Dirty files",
busy="Active clients and dirty files"
D [14/Sep/2015:13:52:40 +0000] cupsdReadClient: 16 POST / HTTP/1.1
D [14/Sep/2015:13:52:40 +0000] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Dirty files"
D [14/Sep/2015:13:52:40 +0000] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2015:13:52:40 +0000] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [14/Sep/2015:13:52:40 +0000] CUPS-Get-Printers
D [14/Sep/2015:13:52:40 +0000] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost
D [14/Sep/2015:13:52:40 +0000] cupsdSetBusyState: newbusy="Dirty files",
busy="Active clients and dirty files"
D [14/Sep/2015:13:52:40 +0000] cupsdReadClient: 16 POST / HTTP/1.1
D [14/Sep/2015:13:52:40 +0000] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Dirty files"
D [14/Sep/2015:13:52:40 +0000] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2015:13:52:40 +0000] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [14/Sep/2015:13:52:40 +0000] CUPS-Get-Printers
D [14/Sep/2015:13:52:40 +0000] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost
D [14/Sep/2015:13:52:40 +0000] cupsdSetBusyState: newbusy="Dirty files",
busy="Active clients and dirty files"
D [14/Sep/2015:13:52:40 +0000] cupsdReadClient: 16 POST / HTTP/1.1
D [14/Sep/2015:13:52:40 +0000] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Dirty files"
D [14/Sep/2015:13:52:40 +0000] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2015:13:52:40 +0000] cupsdReadClient: 16 1.1 Get-Jobs 1
D [14/Sep/2015:13:52:40 +0000] Get-Jobs ipp://localhost/
D [14/Sep/2015:13:52:40 +0000] Returning IPP successful-ok for Get-Jobs
(ipp://localhost/) from localhost
D [14/Sep/2015:13:52:40 +0000] cupsdSetBusyState: newbusy="Dirty files",
busy="Active clients and dirty files"
D [14/Sep/2015:13:52:40 +0000] cupsdReadClient: 16 WAITING Closing on EOF
D [14/Sep/2015:13:52:40 +0000] cupsdCloseClient: 16
D [14/Sep/2015:13:52:40 +0000] cupsdSetBusyState: newbusy="Dirty files",
busy="Dirty files"
I [14/Sep/2015:13:53:07 +0000] Saving job.cache...
D [14/Sep/2015:13:53:07 +0000] cupsdSetBusyState: newbusy="Not busy",
busy="Dirty files"

Link: https://www.cups.org/str.php?L4716
Version: 1.5.3
-----BEGIN PGP SIGNATURE-----
Version: GnuPG v2.0.14 (GNU/Linux)
Comment: GPGTools - https://gpgtools.org

iQIcBAEBCgAGBQJV9tL1AAoJENujp6sI12IjaKYQALMi9y+BLhKQDEpycM2NeQ3b
aditXK+Vx5GN1GqEq6OM55m3+Fs47BKmp7+W/PVVEoCsPc6WNbFW1B1nFKKB/1OJ
lh863nyCWce27UrM+0YfpvFsZXwQ7l8dRaZzvWpppGmda8ILMg3pXYfLmEtFwdk3
RhK7JME7OR2VNyrCBNSNxFfxROLxtiZNQPuqHiZIzNSTwXXU7IdxieagIINpgXXA
Ow+mcJefn1FDw3nJiLvK96/Rr7FpMRJeVn5VfsIb/BxxGjK4RAEXg9fDEDqFweJD
aJjvxzFgfxEg4s4QEsx3TVVMEhwf5LSbdAJAloagsZD8ngo+GnK7NL8LOwR2NE7d
ikHUlA81+2i5GuJZFPnv6/p5MZH5O/xoK7nek+K5QwP4OPhSaBSJrmiRYoB55/d3
P2pSzvtWfND3teCx006LV+e6g352msXFq8cNurgvFiAOQfFKA3qfH9QH20pYzRRL
ZhVTqcr/dhk+OicO5eE9/PRibcnP39G/PrF5PI63dmfnrok35/d+OgkCRoC3uZhR
hCkrujdD7jOCcw5V7lNzKoqDAur7qXgcirljv5fw7kGbxnYm01EgvhC9KMNc1zQB
XVXVXohYyakQRS+EgW/ggXBs/IpokTk0OgcVYiI6AkXCh0dY6gP66MC6/bNbhSLY
db7KjYw/o3Aas5VXvIbP
=1GvD
-----END PGP SIGNATURE-----