[cups] Loading an icm file from a ppd file?

Sat Oct 15 08:42:48 PDT 2016

OK, I'm not really out of the woods.  Looking at this part of the
error log the only mention I see of the icm file is as one of the
parameters passed.  You're saying I need to add a filter that deals
with it?

I bought the ColorMunki in 2014 because I took a picture of a purple
flower that printed blue when I'd exhausted the original toner
cartridges and bought a new set.  My guess is that I'll need to make a
new profile every time I change cartridges, but they're fairly
expensive (~ $350) and I've only needed to do it once so far.  The
printer isn't really designed for photo-quality printing but with a
current profile it's not bad.

I rebooted the computer due to a network issue right before I printed,
I've tried to separate the boot-up messages from the print job
messages.

D [15/Oct/2016:10:00:25 -0400] [Client 17] 2.0 Create-Job 2
D [15/Oct/2016:10:00:25 -0400] Create-Job
ipp://localhost:631/printers/HP_Color_LaserJet_CP2025dn
D [15/Oct/2016:10:00:25 -0400] cupsdMarkDirty(---J-)
D [15/Oct/2016:10:00:25 -0400] cupsdSetBusyState: newbusy="Active
clients and dirty files", busy="Active clients"
D [15/Oct/2016:10:00:25 -0400] add_job: requesting-user-name="root"
I [15/Oct/2016:10:00:25 -0400] [Job 5] Adding start banner page "none".
D [15/Oct/2016:10:00:25 -0400] Discarding unused job-created event...
I [15/Oct/2016:10:00:25 -0400] [Job 5] Queued on
"HP_Color_LaserJet_CP2025dn" by "root".
D [15/Oct/2016:10:00:25 -0400] [Client 17] Returning IPP successful-ok
for Create-Job (ipp://localhost:631/printers/HP_Color_LaserJet_CP2025dn)
from localhost
D [15/Oct/2016:10:00:25 -0400] [Client 17] Content-Length: 181
D [15/Oct/2016:10:00:25 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:25 -0400] [Client 17] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=181,
response=0x562c9c80(IPP_IDLE), pipe_pid=0, file=-1
D [15/Oct/2016:10:00:25 -0400] [Client 17] Writing IPP response,
ipp_state=DATA, old wused=0, new wused=0
D [15/Oct/2016:10:00:25 -0400] [Client 17] bytes=0, http_state=0,
data_remaining=0
D [15/Oct/2016:10:00:25 -0400] [Client 17] Waiting for request.
D [15/Oct/2016:10:00:25 -0400] cupsdSetBusyState: newbusy="Dirty
files", busy="Active clients and dirty files"
D [15/Oct/2016:10:00:25 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:25 -0400] [Client 17] POST
/printers/HP_Color_LaserJet_CP2025dn HTTP/1.1
D [15/Oct/2016:10:00:25 -0400] cupsdSetBusyState: newbusy="Active
clients and dirty files", busy="Dirty files"
D [15/Oct/2016:10:00:25 -0400] [Client 17] No authentication data provided.
D [15/Oct/2016:10:00:25 -0400] [Client 17] 2.0 Send-Document 3
D [15/Oct/2016:10:00:25 -0400] cupsd is not idle any more, canceling shutdown.
...  [hundreds of lines of this trimmed for posting]
D [15/Oct/2016:10:00:26 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:26 -0400] Send-Document
ipp://localhost:631/printers/HP_Color_LaserJet_CP2025dn
D [15/Oct/2016:10:00:26 -0400] cupsdIsAuthorized: requesting-user-name="root"
D [15/Oct/2016:10:00:26 -0400] [Job 5] Auto-typing file...
D [15/Oct/2016:10:00:26 -0400] [Job 5] Request file type is image/jpeg.
D [15/Oct/2016:10:00:26 -0400] cupsdMarkDirty(---J-)
D [15/Oct/2016:10:00:26 -0400] cupsdSetBusyState: newbusy="Active
clients and dirty files", busy="Active clients and dirty files"
I [15/Oct/2016:10:00:26 -0400] [Job 5] File of type image/jpeg queued by "root".
I [15/Oct/2016:10:00:26 -0400] [Job 5] Adding end banner page "none".
D [15/Oct/2016:10:00:26 -0400] cupsdMarkDirty(---J-)
D [15/Oct/2016:10:00:26 -0400] cupsdSetBusyState: newbusy="Active
clients and dirty files", busy="Active clients and dirty files"
D [15/Oct/2016:10:00:26 -0400] [Job 5] time-at-processing=1476540026
D [15/Oct/2016:10:00:26 -0400] cupsdMarkDirty(---J-)
D [15/Oct/2016:10:00:26 -0400] cupsdSetBusyState: newbusy="Active
clients and dirty files", busy="Active clients and dirty files"
D [15/Oct/2016:10:00:26 -0400] cupsdSetBusyState: newbusy="Active
clients and dirty files", busy="Active clients and dirty files"
D [15/Oct/2016:10:00:26 -0400] Discarding unused printer-state-changed event...
D [15/Oct/2016:10:00:26 -0400] [Job 5] 2 filters for job:
D [15/Oct/2016:10:00:26 -0400] [Job 5] imagetoraster (image/jpeg to
application/vnd.cups-raster, cost 100)
D [15/Oct/2016:10:00:26 -0400] [Job 5] hpcups
(application/vnd.cups-raster to printer/HP_Color_LaserJet_CP2025dn,
cost 0)
D [15/Oct/2016:10:00:26 -0400] [Job 5] job-sheets=none,none
D [15/Oct/2016:10:00:26 -0400] [Job 5] argv[0]="HP_Color_LaserJet_CP2025dn"
D [15/Oct/2016:10:00:26 -0400] [Job 5] argv[1]="5"
D [15/Oct/2016:10:00:26 -0400] [Job 5] argv[2]="root"
D [15/Oct/2016:10:00:26 -0400] [Job 5] argv[3]="purple.jpg"
D [15/Oct/2016:10:00:26 -0400] [Job 5] argv[4]="1"
D [15/Oct/2016:10:00:26 -0400] [Job 5]
argv[5]="cupsICCProfile=/etc/cups/icm/HP_cp2025dn_2014_colormunki.icm
finishings=3 fit-to-page media=letter number-up=1
job-uuid=urn:uuid:4dd97a16-4f3e-3bb2-7832-49635ec27139
job-originating-host-name=localhost time-at-creation=1476540025
time-at-processing=1476540026 PageSize=Letter"
D [15/Oct/2016:10:00:26 -0400] [Job 5] argv[6]="/var/spool/cups/d00005-001"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [15/Oct/2016:10:00:26 -0400] [Job 5]
envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [15/Oct/2016:10:00:26 -0400] [Job 5]
envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [15/Oct/2016:10:00:26 -0400] [Job 5]
envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[8]="HOME=/var/spool/cups/tmp"
D [15/Oct/2016:10:00:26 -0400] [Job 5]
envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[10]="SERVER_ADMIN=root at raspberrypi2"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[11]="SOFTWARE=CUPS/1.7.5"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[13]="USER=root"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[14]="CUPS_MAX_MESSAGE=2047"
D [15/Oct/2016:10:00:26 -0400] [Job 5]
envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[17]="IPP_PORT=631"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[18]="CHARSET=utf-8"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[19]="LANG=en_US.UTF-8"
D [15/Oct/2016:10:00:26 -0400] [Job 5]
envp[20]="PPD=/etc/cups/ppd/HP_Color_LaserJet_CP2025dn.ppd"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[21]="RIP_MAX_CACHE=128m"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[22]="CONTENT_TYPE=image/jpeg"
D [15/Oct/2016:10:00:26 -0400] [Job 5]
envp[23]="DEVICE_URI=hp:/usb/HP_Color_LaserJet_CP2025dn?serial=00CNGS309277"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[24]="PRINTER_INFO=HP Color
LaserJet CP2025dn"
D [15/Oct/2016:10:00:26 -0400] [Job 5]
envp[25]="PRINTER_LOCATION=upstairs_10/13"
D [15/Oct/2016:10:00:26 -0400] [Job 5]
envp[26]="PRINTER=HP_Color_LaserJet_CP2025dn"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[27]="PRINTER_STATE_REASONS=none"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[28]="CUPS_FILETYPE=document"
D [15/Oct/2016:10:00:26 -0400] [Job 5]
envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-raster"
D [15/Oct/2016:10:00:26 -0400] [Job 5] envp[30]="AUTH_I****"
I [15/Oct/2016:10:00:26 -0400] [Job 5] Started filter
/usr/lib/cups/filter/imagetoraster (PID 1174)
I [15/Oct/2016:10:00:26 -0400] [Job 5] Started filter
/usr/lib/cups/filter/hpcups (PID 1175)
I [15/Oct/2016:10:00:26 -0400] [Job 5] Started backend
/usr/lib/cups/backend/hp (PID 1176)
D [15/Oct/2016:10:00:26 -0400] Discarding unused job-state-changed event...
D [15/Oct/2016:10:00:26 -0400] [Client 17] Returning IPP successful-ok
for Send-Document
(ipp://localhost:631/printers/HP_Color_LaserJet_CP2025dn) from
localhost
D [15/Oct/2016:10:00:26 -0400] [Client 17] Content-Length: 173
D [15/Oct/2016:10:00:26 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:26 -0400] [Client 17] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=173,
response=0x562c9ff0(IPP_IDLE), pipe_pid=0, file=-1
D [15/Oct/2016:10:00:26 -0400] [Client 17] Writing IPP response,
ipp_state=DATA, old wused=0, new wused=0
D [15/Oct/2016:10:00:26 -0400] [Client 17] bytes=0, http_state=0,
data_remaining=0
D [15/Oct/2016:10:00:26 -0400] [Client 17] Waiting for request.
D [15/Oct/2016:10:00:26 -0400] cupsdSetBusyState: newbusy="Printing
jobs and dirty files", busy="Active clients and dirty files"
D [15/Oct/2016:10:00:26 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:26 -0400] [Client 17] HTTP_STATE_WAITING Closing on EOF
D [15/Oct/2016:10:00:26 -0400] [Client 17] Closing connection.
D [15/Oct/2016:10:00:26 -0400] cupsdSetBusyState: newbusy="Printing
jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Oct/2016:10:00:26 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:26 -0400] [Job 5] Page = 612x792; 18,14 to 594,778
D [15/Oct/2016:10:00:26 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:26 -0400] [Job 5] Color Manager: Calibration Mode/Off
D [15/Oct/2016:10:00:26 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:26 -0400] [Job 5] Calling
FindDeviceById(cups-HP_Color_LaserJet_CP2025dn)
D [15/Oct/2016:10:00:26 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:26 -0400] [Job 5] Found device
/org/freedesktop/ColorManager/devices/cups_HP_Color_LaserJet_CP2025dn
D [15/Oct/2016:10:00:26 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:26 -0400] [Job 5] Calling
org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors)
D [15/Oct/2016:10:00:26 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:26 -0400] [Job 5] Searching for profile "/Plain"...
D [15/Oct/2016:10:00:26 -0400] cupsd is not idle any more, canceling shutdown.
I [15/Oct/2016:10:00:26 -0400] [Job 5] Loading print file.
D [15/Oct/2016:10:00:26 -0400] cupsdMarkDirty(---J-)
D [15/Oct/2016:10:00:26 -0400] cupsdSetBusyState: newbusy="Printing
jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Oct/2016:10:00:26 -0400] cupsdMarkDirty(---J-)
D [15/Oct/2016:10:00:26 -0400] cupsdSetBusyState: newbusy="Printing
jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Oct/2016:10:00:26 -0400] [Job 5] Set job-printer-state-message
to "Loading print file.", current level=INFO
D [15/Oct/2016:10:00:26 -0400] Discarding unused job-progress event...
D [15/Oct/2016:10:00:26 -0400] Discarding unused printer-state-changed event...
D [15/Oct/2016:10:00:26 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:26 -0400] [Job 5] num_components = 3
D [15/Oct/2016:10:00:26 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:26 -0400] [Job 5] jpeg_color_space = JCS_YCbCr
D [15/Oct/2016:10:00:26 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:26 -0400] [Job 5] Converting image to RGB...
D [15/Oct/2016:10:00:26 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:26 -0400] [Job 5] JPEG image 4896x3672x3, 128x128 PPI
D [15/Oct/2016:10:00:26 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] [Job 5] Before scaling: xppi=0, yppi=0, zoom=1.00
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] [Job 5] Before scaling: xprint=8.0, yprint=10.6
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] [Job 5] img->xppi = 128, img->yppi =
128, aspect = 1.000000
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] [Job 5] Portrait size is 8.00 x 6.00 inches
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] [Job 5] Landscape size is 10.60 x 7.95 inches
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] [Job 5] Auto orientation...
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] [Job 5] Using landscape orientation...
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] [Job 5] xpages = 1x10.60in, ypages = 1x7.95in
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] [Job 5] PageSize = [612 792]
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] [Job 5] cupsWidth = 2400
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] [Job 5] cupsHeight = 3180
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] [Job 5] cupsBitsPerColor = 8
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] [Job 5] cupsBitsPerPixel = 24
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] [Job 5] cupsBytesPerLine = 7200
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] [Job 5] cupsColorOrder = 0
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] [Job 5] cupsColorSpace = 1
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] [Job 5] img->colorspace = 3
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
I [15/Oct/2016:10:00:27 -0400] [Job 5] Formatting page 1.
D [15/Oct/2016:10:00:27 -0400] cupsdMarkDirty(---J-)
D [15/Oct/2016:10:00:27 -0400] cupsdSetBusyState: newbusy="Printing
jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Oct/2016:10:00:27 -0400] [Job 5] Set job-printer-state-message
to "Formatting page 1.", current level=INFO
D [15/Oct/2016:10:00:27 -0400] Discarding unused job-progress event...
D [15/Oct/2016:10:00:27 -0400] Discarding unused printer-state-changed event...
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] [Job 5] Writing 0 leading blank lines...
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] [Job 5] STATE: +connecting-to-device
D [15/Oct/2016:10:00:27 -0400] cupsdMarkDirty(---J-)
D [15/Oct/2016:10:00:27 -0400] cupsdSetBusyState: newbusy="Dirty
files", busy="Printing jobs and dirty files"
D [15/Oct/2016:10:00:27 -0400] [Job 5] PAGE: 1 1
D [15/Oct/2016:10:00:27 -0400] Discarding unused job-progress event...
D [15/Oct/2016:10:00:27 -0400] Discarding unused printer-state-changed event...
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:27 -0400] [Job 5] STATE: -connecting-to-device
D [15/Oct/2016:10:00:27 -0400] cupsdMarkDirty(---J-)
D [15/Oct/2016:10:00:27 -0400] cupsdSetBusyState: newbusy="Printing
jobs and dirty files", busy="Dirty files"
D [15/Oct/2016:10:00:27 -0400] [Job 5] STATE:
-media-empty-error,media-jam-error,hplip.plugin-error,cover-open-error,toner-empty-error,other
D [15/Oct/2016:10:00:27 -0400] Discarding unused printer-state-changed event...
D [15/Oct/2016:10:00:27 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:28 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:48 -0400] [Job 5] Writing 1 trailing blank lines...
D [15/Oct/2016:10:00:48 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:48 -0400] [Job 5] PID 1174
(/usr/lib/cups/filter/imagetoraster) exited with no errors.
D [15/Oct/2016:10:00:48 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:48 -0400] [Job 5] PID 1175
(/usr/lib/cups/filter/hpcups) exited with no errors.
D [15/Oct/2016:10:00:48 -0400] cupsd is not idle any more, canceling shutdown.
I [15/Oct/2016:10:00:56 -0400] Saving job.cache...
D [15/Oct/2016:10:00:56 -0400] cupsdSetBusyState: newbusy="Printing
jobs", busy="Printing jobs and dirty files"
D [15/Oct/2016:10:00:56 -0400] Report: clients=1
D [15/Oct/2016:10:00:56 -0400] Report: jobs=4
D [15/Oct/2016:10:00:56 -0400] Report: jobs-active=1
D [15/Oct/2016:10:00:56 -0400] Report: printers=2
D [15/Oct/2016:10:00:56 -0400] Report: stringpool-string-count=5824
D [15/Oct/2016:10:00:56 -0400] Report: stringpool-alloc-bytes=11872
D [15/Oct/2016:10:00:56 -0400] Report: stringpool-total-bytes=110248
D [15/Oct/2016:10:00:56 -0400] cupsd is not idle any more, canceling shutdown.
I [15/Oct/2016:10:00:57 -0400] [Job 5] ready to print
D [15/Oct/2016:10:00:57 -0400] cupsdMarkDirty(---J-)
D [15/Oct/2016:10:00:57 -0400] cupsdSetBusyState: newbusy="Printing
jobs and dirty files", busy="Printing jobs"
D [15/Oct/2016:10:00:57 -0400] [Job 5] Set job-printer-state-message
to "ready to print", current level=INFO
D [15/Oct/2016:10:00:57 -0400] Discarding unused job-progress event...
D [15/Oct/2016:10:00:57 -0400] Discarding unused printer-state-changed event...
D [15/Oct/2016:10:00:57 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:57 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:57 -0400] [Job 5] PID 1176
(/usr/lib/cups/backend/hp) exited with no errors.
D [15/Oct/2016:10:00:57 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:57 -0400] [Job 5] time-at-completed=1476540057
D [15/Oct/2016:10:00:57 -0400] Discarding unused job-completed event...
I [15/Oct/2016:10:00:57 -0400] [Job 5] Job completed.
D [15/Oct/2016:10:00:57 -0400] cupsdMarkDirty(---J-)
D [15/Oct/2016:10:00:57 -0400] cupsdSetBusyState: newbusy="Printing
jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Oct/2016:10:00:57 -0400] cupsdSetBusyState: newbusy="Printing
jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Oct/2016:10:00:57 -0400] Discarding unused printer-state-changed event...
D [15/Oct/2016:10:00:57 -0400] cupsdMarkDirty(---J-)
D [15/Oct/2016:10:00:57 -0400] cupsdSetBusyState: newbusy="Printing
jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Oct/2016:10:00:57 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:00:58 -0400] [Job 5] Unloading...
D [15/Oct/2016:10:00:58 -0400] cupsd is not idle any more, canceling shutdown.
I [15/Oct/2016:10:01:28 -0400] Saving job.cache...
D [15/Oct/2016:10:01:28 -0400] cupsdSetBusyState: newbusy="Not busy",
busy="Printing jobs and dirty files"
D [15/Oct/2016:10:01:28 -0400] cupsd is not idle any more, canceling shutdown.
D [15/Oct/2016:10:03:37 -0400] Closing client 18 after 300 seconds of
inactivity...
D [15/Oct/2016:10:03:37 -0400] [Client 18] Closing connection.
D [15/Oct/2016:10:03:37 -0400] cupsdSetBusyState: newbusy="Not busy",
busy="Not busy"
D [15/Oct/2016:10:03:37 -0400] Report: clients=0
D [15/Oct/2016:10:03:37 -0400] Report: jobs=4
D [15/Oct/2016:10:03:37 -0400] Report: jobs-active=0
D [15/Oct/2016:10:03:37 -0400] Report: printers=2
D [15/Oct/2016:10:03:37 -0400] Report: stringpool-string-count=5779
D [15/Oct/2016:10:03:37 -0400] Report: stringpool-alloc-bytes=10688
D [15/Oct/2016:10:03:37 -0400] Report: stringpool-total-bytes=109320
D [15/Oct/2016:10:03:37 -0400] cupsd is not idle any more, canceling shutdown.

-- 
Credit is the root of all evil.  - AB1JX