D [26/Jan/2016:23:32:59 +0100] [Client 17] Sending file.
D [26/Jan/2016:23:32:59 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:32:59 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=13844, response=(nil)(), pipe_pid=0, file=19
D [26/Jan/2016:23:32:59 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:32:59 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=11796, response=(nil)(), pipe_pid=0, file=19
D [26/Jan/2016:23:32:59 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:32:59 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9748, response=(nil)(), pipe_pid=0, file=19
D [26/Jan/2016:23:32:59 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:32:59 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7700, response=(nil)(), pipe_pid=0, file=19
D [26/Jan/2016:23:32:59 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:32:59 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=5652, response=(nil)(), pipe_pid=0, file=19
D [26/Jan/2016:23:32:59 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:32:59 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3604, response=(nil)(), pipe_pid=0, file=19
D [26/Jan/2016:23:32:59 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:32:59 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1556, response=(nil)(), pipe_pid=0, file=19
D [26/Jan/2016:23:32:59 +0100] [Client 17] Waiting for request.
D [26/Jan/2016:23:32:59 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:32:59 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:32:59 +0100] [Client 18] POST / HTTP/1.1
D [26/Jan/2016:23:32:59 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [26/Jan/2016:23:32:59 +0100] [Client 18] No authentication data provided.
D [26/Jan/2016:23:32:59 +0100] [Client 18] 2.0 Get-Jobs 20
D [26/Jan/2016:23:32:59 +0100] Get-Jobs ipp://localhost/printers/
D [26/Jan/2016:23:32:59 +0100] [Client 18] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [26/Jan/2016:23:32:59 +0100] [Client 18] Content-Length: 75
D [26/Jan/2016:23:32:59 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:32:59 +0100] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f51759236e0(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:32:59 +0100] [Client 18] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:32:59 +0100] [Client 18] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:32:59 +0100] [Client 18] Waiting for request.
D [26/Jan/2016:23:32:59 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:32:59 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:32:59 +0100] [Client 18] POST / HTTP/1.1
D [26/Jan/2016:23:32:59 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [26/Jan/2016:23:32:59 +0100] [Client 18] No authentication data provided.
D [26/Jan/2016:23:32:59 +0100] [Client 18] 2.0 Get-Jobs 21
D [26/Jan/2016:23:32:59 +0100] Get-Jobs ipp://localhost/printers/
D [26/Jan/2016:23:32:59 +0100] [Job 57] Loading attributes...
D [26/Jan/2016:23:32:59 +0100] [Client 18] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [26/Jan/2016:23:32:59 +0100] [Client 18] Content-Length: 166
D [26/Jan/2016:23:32:59 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:32:59 +0100] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=166, response=0x7f51758dd4b0(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:32:59 +0100] [Client 18] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:32:59 +0100] [Client 18] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:32:59 +0100] [Client 18] Waiting for request.
D [26/Jan/2016:23:32:59 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:32:59 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:32:59 +0100] [Client 18] POST / HTTP/1.1
D [26/Jan/2016:23:32:59 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [26/Jan/2016:23:32:59 +0100] [Client 18] No authentication data provided.
D [26/Jan/2016:23:32:59 +0100] [Client 18] 2.0 Create-Printer-Subscriptions 22
D [26/Jan/2016:23:32:59 +0100] Create-Printer-Subscriptions /
D [26/Jan/2016:23:32:59 +0100] create_subscriptions(con=0x7f51759398b0(18), uri="/")
D [26/Jan/2016:23:32:59 +0100] pullmethod="ippget"
D [26/Jan/2016:23:32:59 +0100] notify-lease-duration=86400
D [26/Jan/2016:23:32:59 +0100] notify-time-interval=0
D [26/Jan/2016:23:32:59 +0100] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)")
D [26/Jan/2016:23:32:59 +0100] Added subscription #160 for server.
D [26/Jan/2016:23:32:59 +0100] cupsdMarkDirty(----S)
D [26/Jan/2016:23:32:59 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:32:59 +0100] [Client 18] Returning IPP successful-ok for Create-Printer-Subscriptions (/) from localhost
D [26/Jan/2016:23:32:59 +0100] [Client 18] Content-Length: 107
D [26/Jan/2016:23:32:59 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:32:59 +0100] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=107, response=0x7f5175954530(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:32:59 +0100] [Client 18] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:32:59 +0100] [Client 18] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:32:59 +0100] [Client 18] Waiting for request.
D [26/Jan/2016:23:32:59 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:32:59 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:00 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:00 +0100] [Client 18] POST / HTTP/1.1
D [26/Jan/2016:23:33:00 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:00 +0100] [Client 18] No authentication data provided.
D [26/Jan/2016:23:33:00 +0100] [Client 18] 2.0 Get-Notifications 23
D [26/Jan/2016:23:33:00 +0100] Get-Notifications /
D [26/Jan/2016:23:33:00 +0100] cupsdIsAuthorized: requesting-user-name="tower"
D [26/Jan/2016:23:33:00 +0100] [Client 18] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [26/Jan/2016:23:33:00 +0100] [Client 18] Content-Length: 127
D [26/Jan/2016:23:33:00 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:00 +0100] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f51758dd4b0(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:00 +0100] [Client 18] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:00 +0100] [Client 18] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:00 +0100] [Client 18] Waiting for request.
D [26/Jan/2016:23:33:00 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:33:00 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:01 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] Accepted from localhost (Domain)
D [26/Jan/2016:23:33:03 +0100] [Client 19] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] POST /printers/AL-C1100 HTTP/1.1
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 19] No authentication data provided.
D [26/Jan/2016:23:33:03 +0100] [Client 19] 2.0 Print-Job 24
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] Print-Job ipp://localhost/printers/AL-C1100
D [26/Jan/2016:23:33:03 +0100] [Job ???] Auto-typing file...
I [26/Jan/2016:23:33:03 +0100] [Job ???] Request file type is application/vnd.cups-pdf-banner.
D [26/Jan/2016:23:33:03 +0100] cupsdMarkDirty(---J-)
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:33:03 +0100] add_job: requesting-user-name="tower"
D [26/Jan/2016:23:33:03 +0100] Adding default job-sheets values "none,none"...
I [26/Jan/2016:23:33:03 +0100] [Job 58] Adding start banner page "none".
D [26/Jan/2016:23:33:03 +0100] cupsdMarkDirty(----S)
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsdMarkDirty(---J-)
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [26/Jan/2016:23:33:03 +0100] [Job 58] Adding end banner page "none".
I [26/Jan/2016:23:33:03 +0100] [Job 58] File of type application/vnd.cups-pdf-banner queued by "tower".
D [26/Jan/2016:23:33:03 +0100] [Job 58] hold_until=0
I [26/Jan/2016:23:33:03 +0100] [Job 58] Queued on "AL-C1100" by "tower".
D [26/Jan/2016:23:33:03 +0100] [Job 58] time-at-processing=1453847583
D [26/Jan/2016:23:33:03 +0100] cupsdMarkDirty(---J-)
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsdMarkDirty(----S)
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Job 58] 4 filters for job:
D [26/Jan/2016:23:33:03 +0100] [Job 58] bannertopdf (application/vnd.cups-pdf-banner to application/pdf, cost 32)
D [26/Jan/2016:23:33:03 +0100] [Job 58] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)
D [26/Jan/2016:23:33:03 +0100] [Job 58] pdftops (application/vnd.cups-pdf to application/vnd.cups-postscript, cost 100)
D [26/Jan/2016:23:33:03 +0100] [Job 58] foomatic-rip (application/vnd.cups-postscript to printer/AL-C1100, cost 0)
D [26/Jan/2016:23:33:03 +0100] [Job 58] job-sheets=none,none
D [26/Jan/2016:23:33:03 +0100] [Job 58] argv[0]="AL-C1100"
D [26/Jan/2016:23:33:03 +0100] [Job 58] argv[1]="58"
D [26/Jan/2016:23:33:03 +0100] [Job 58] argv[2]="tower"
D [26/Jan/2016:23:33:03 +0100] [Job 58] argv[3]="Test Page"
D [26/Jan/2016:23:33:03 +0100] [Job 58] argv[4]="1"
D [26/Jan/2016:23:33:03 +0100] [Job 58] argv[5]="job-uuid=urn:uuid:b3815b18-5370-3c99-6fa9-d2085f15b81a job-originating-host-name=localhost time-at-creation=1453847583 time-at-processing=1453847583"
D [26/Jan/2016:23:33:03 +0100] [Job 58] argv[6]="/var/spool/cups/d00058-001"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[8]="HOME=/var/spool/cups/tmp"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[10]="SERVER_ADMIN=root@TOWER"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[11]="SOFTWARE=CUPS/1.7.5"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[13]="USER=root"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[14]="CUPS_MAX_MESSAGE=2047"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[17]="IPP_PORT=631"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[18]="CHARSET=utf-8"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[19]="LANG=de_DE.UTF-8"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[20]="PPD=/etc/cups/ppd/AL-C1100.ppd"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[21]="RIP_MAX_CACHE=128m"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[22]="CONTENT_TYPE=application/vnd.cups-pdf-banner"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[23]="DEVICE_URI=usb://EPSON/AL-C1100?serial=31PGZPZ10736651201"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[24]="PRINTER_INFO=EPSON AL-C1100"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[25]="PRINTER_LOCATION=TOWER"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[26]="PRINTER=AL-C1100"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[27]="PRINTER_STATE_REASONS=none"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[28]="CUPS_FILETYPE=document"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
D [26/Jan/2016:23:33:03 +0100] [Job 58] envp[30]="AUTH_I****"
I [26/Jan/2016:23:33:03 +0100] [Job 58] Started filter /usr/lib/cups/filter/bannertopdf (PID 30515)
I [26/Jan/2016:23:33:03 +0100] [Job 58] Started filter /usr/lib/cups/filter/pdftopdf (PID 30516)
I [26/Jan/2016:23:33:03 +0100] [Job 58] Started filter /usr/lib/cups/filter/pdftops (PID 30517)
I [26/Jan/2016:23:33:03 +0100] [Job 58] Started filter /usr/lib/cups/filter/foomatic-rip (PID 30518)
I [26/Jan/2016:23:33:03 +0100] [Job 58] Started backend /usr/lib/cups/backend/usb (PID 30519)
D [26/Jan/2016:23:33:03 +0100] cupsdMarkDirty(----S)
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 19] Returning IPP successful-ok for Print-Job (ipp://localhost/printers/AL-C1100) from localhost
D [26/Jan/2016:23:33:03 +0100] [Client 19] Content-Length: 174
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=174, response=0x7f51758def00(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:03 +0100] [Client 19] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:03 +0100] [Client 19] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:03 +0100] [Client 19] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Loading USB quirks from "/usr/share/cups/usb".
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Loaded 113 quirks.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Printing on printer with URI: usb://EPSON/AL-C1100?serial=31PGZPZ10736651201
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] libusb_get_device_list=9
D [26/Jan/2016:23:33:03 +0100] [Job 58] pdftops - copying to temp print file "/var/spool/cups/tmp/0773556ae77e5"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] STATE: +connecting-to-device
D [26/Jan/2016:23:33:03 +0100] cupsdMarkDirty(---J-)
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsdMarkDirty(----S)
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] STATE: -connecting-to-device
D [26/Jan/2016:23:33:03 +0100] cupsdMarkDirty(---J-)
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsdMarkDirty(----S)
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Calling FindDeviceById(cups-AL-C1100)
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found device /org/freedesktop/ColorManager/devices/cups_AL_C1100
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Calling org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors)
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Device protocol: 2
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] 'CM Color Calibration' Mode in SPOOLER-LESS: Off
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Getting input from file
D [26/Jan/2016:23:33:03 +0100] [Job 58] foomatic-rip version 1.0.61 running...
D [26/Jan/2016:23:33:03 +0100] [Job 58] Parsing PPD file ...
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Added option ColorSpace
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Added option PageSize
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Added option ImageableArea
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Added option PaperDimension
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Added option MediaType
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Added option Color
D [26/Jan/2016:23:33:03 +0100] [Job 58] Added option Duplex
D [26/Jan/2016:23:33:03 +0100] [Job 58] Added option TonerSave
D [26/Jan/2016:23:33:03 +0100] [Job 58] Added option Resolution
D [26/Jan/2016:23:33:03 +0100] [Job 58] Added option InputSlot
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Added option Collate
D [26/Jan/2016:23:33:03 +0100] [Job 58] Added option Copies
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Added option Font
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Parameter Summary
D [26/Jan/2016:23:33:03 +0100] [Job 58] -----------------
D [26/Jan/2016:23:33:03 +0100] [Job 58] Spooler: cups
D [26/Jan/2016:23:33:03 +0100] [Job 58] Printer: AL-C1100
D [26/Jan/2016:23:33:03 +0100] [Job 58] Shell: /bin/bash
D [26/Jan/2016:23:33:03 +0100] [Job 58] PPD file: /etc/cups/ppd/AL-C1100.ppd
D [26/Jan/2016:23:33:03 +0100] [Job 58] ATTR file:
D [26/Jan/2016:23:33:03 +0100] [Job 58] Printer model: EPSON AL-C1100, ESC/PageS Filter
D [26/Jan/2016:23:33:03 +0100] [Job 58] Job title: Test Page
D [26/Jan/2016:23:33:03 +0100] [Job 58] File(s) to be printed:
D [26/Jan/2016:23:33:03 +0100] [Job 58] <STDIN>
D [26/Jan/2016:23:33:03 +0100] [Job 58] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [26/Jan/2016:23:33:03 +0100] [Job 58] Printing system options:
D [26/Jan/2016:23:33:03 +0100] [Job 58] Pondering option 'job-uuid=urn:uuid:b3815b18-5370-3c99-6fa9-d2085f15b81a'
D [26/Jan/2016:23:33:03 +0100] [Job 58] Unknown option job-uuid=urn:uuid:b3815b18-5370-3c99-6fa9-d2085f15b81a.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Pondering option 'job-originating-host-name=localhost'
D [26/Jan/2016:23:33:03 +0100] [Job 58] Unknown option job-originating-host-name=localhost.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Pondering option 'time-at-creation=1453847583'
D [26/Jan/2016:23:33:03 +0100] [Job 58] Unknown option time-at-creation=1453847583.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Pondering option 'time-at-processing=1453847583'
D [26/Jan/2016:23:33:03 +0100] [Job 58] Unknown option time-at-processing=1453847583.
D [26/Jan/2016:23:33:03 +0100] [Job 58] CM Color Calibration Mode in CUPS: Off
D [26/Jan/2016:23:33:03 +0100] [Job 58] Options from the PPD file:
D [26/Jan/2016:23:33:03 +0100] [Job 58] ================================================
D [26/Jan/2016:23:33:03 +0100] [Job 58] File: <STDIN>
D [26/Jan/2016:23:33:03 +0100] [Job 58] ================================================
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
I [26/Jan/2016:23:33:03 +0100] [Job 58] Sende Daten zum Drucker.
D [26/Jan/2016:23:33:03 +0100] cupsdMarkDirty(---J-)
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Job 58] Set job-printer-state-message to "Sende Daten zum Drucker.", current level=INFO
D [26/Jan/2016:23:33:03 +0100] cupsdMarkDirty(----S)
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsdMarkDirty(----S)
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] PDF template file doesn't have form. It's okay.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] PID 30515 (/usr/lib/cups/filter/bannertopdf) exited with no errors.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] PID 30516 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Printer make and model: Epson ESC/PageS Filter
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Running command line for pstops: pstops 58 tower 'Test Page' 1 'job-uuid=urn:uuid:b3815b18-5370-3c99-6fa9-d2085f15b81a job-originating-host-name=localhost time-at-creation=1453847583 time-at-processing=1453847583'
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Using image rendering resolution 300 dpi
D [26/Jan/2016:23:33:03 +0100] [Job 58] Running command line for gs: gs -q -dNOPAUSE -dBATCH -dSAFER -sDEVICE=ps2write -sOUTPUTFILE=%stdout -dLanguageLevel=3 -r300 -dCompressFonts=false -dNoT3CCITT -dNOINTERPOLATE -c 'save pop' -f /var/spool/cups/tmp/0773556ae77e5
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Started filter gs (PID 30524)
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Started filter pstops (PID 30525)
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Page = 595x842; 0,0 to 595,842
D [26/Jan/2016:23:33:03 +0100] [Job 58] slow_collate=0, slow_duplex=0, slow_order=0
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Before copy_comments - %!PS-Adobe-3.0
D [26/Jan/2016:23:33:03 +0100] [Job 58] %!PS-Adobe-3.0
D [26/Jan/2016:23:33:03 +0100] [Job 58] %%BoundingBox: 0 0 595 842
D [26/Jan/2016:23:33:03 +0100] [Job 58] %%Creator: GPL Ghostscript 906 (ps2write)
D [26/Jan/2016:23:33:03 +0100] [Job 58] %%LanguageLevel: 2
D [26/Jan/2016:23:33:03 +0100] [Job 58] %%CreationDate: D:20160126233303+01'00'
D [26/Jan/2016:23:33:03 +0100] [Job 58] %%Pages: 1
D [26/Jan/2016:23:33:03 +0100] [Job 58] %%EndComments
D [26/Jan/2016:23:33:03 +0100] [Job 58] Before copy_prolog - %%BeginProlog
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Filetype: PostScript
D [26/Jan/2016:23:33:03 +0100] [Job 58] Reading PostScript input ...
D [26/Jan/2016:23:33:03 +0100] [Job 58] --> This document is DSC-conforming!
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found %RBINumCopies: 1
D [26/Jan/2016:23:33:03 +0100] [Job 58] -----------
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %%BeginProlog
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Before copy_setup - %%Page: 1 1
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Before page loop - %%Page: 1 1
D [26/Jan/2016:23:33:03 +0100] [Job 58] Copying page 1...
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] pagew = 595.0, pagel = 842.0
D [26/Jan/2016:23:33:03 +0100] [Job 58] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [26/Jan/2016:23:33:03 +0100] [Job 58] PageLeft = 0.0, PageRight = 595.0
D [26/Jan/2016:23:33:03 +0100] [Job 58] PageTop = 842.0, PageBottom = 0.0
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] PageWidth = 595.0, PageLength = 842.0
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %%EndProlog
D [26/Jan/2016:23:33:03 +0100] [Job 58] -----------
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %%BeginSetup
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %%BeginFeature: *Collate on
D [26/Jan/2016:23:33:03 +0100] [Job 58] Option: Collate=on
D [26/Jan/2016:23:33:03 +0100] [Job 58] Setting option
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %% FoomaticRIPOptionSetting: Collate=on
D [26/Jan/2016:23:33:03 +0100] [Job 58] Option: Collate=on
D [26/Jan/2016:23:33:03 +0100] [Job 58] Setting option
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %%BeginFeature: *Copies 1
D [26/Jan/2016:23:33:03 +0100] [Job 58] Option: Copies=1
D [26/Jan/2016:23:33:03 +0100] [Job 58] Setting option
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %% FoomaticRIPOptionSetting: Copies=1
D [26/Jan/2016:23:33:03 +0100] [Job 58] Option: Copies=1
D [26/Jan/2016:23:33:03 +0100] [Job 58] Setting option
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %%BeginFeature: *InputSlot autoselection
D [26/Jan/2016:23:33:03 +0100] [Job 58] Option: InputSlot=autoselection
D [26/Jan/2016:23:33:03 +0100] [Job 58] Setting option
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %% FoomaticRIPOptionSetting: InputSlot=autoselection
D [26/Jan/2016:23:33:03 +0100] [Job 58] Option: InputSlot=autoselection
D [26/Jan/2016:23:33:03 +0100] [Job 58] Setting option
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %%BeginFeature: *MediaType normal
D [26/Jan/2016:23:33:03 +0100] [Job 58] Option: MediaType=normal
D [26/Jan/2016:23:33:03 +0100] [Job 58] Setting option
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %% FoomaticRIPOptionSetting: MediaType=normal
D [26/Jan/2016:23:33:03 +0100] [Job 58] Option: MediaType=normal
D [26/Jan/2016:23:33:03 +0100] [Job 58] Setting option
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %%BeginFeature: *PageSize a4
D [26/Jan/2016:23:33:03 +0100] [Job 58] Option: PageSize=a4
D [26/Jan/2016:23:33:03 +0100] [Job 58] Setting option
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %% FoomaticRIPOptionSetting: PageSize=a4
D [26/Jan/2016:23:33:03 +0100] [Job 58] Option: PageSize=a4
D [26/Jan/2016:23:33:03 +0100] [Job 58] Setting option
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %%BeginFeature: *TonerSave false
D [26/Jan/2016:23:33:03 +0100] [Job 58] Option: TonerSave=false
D [26/Jan/2016:23:33:03 +0100] [Job 58] Setting option
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %% FoomaticRIPOptionSetting: TonerSave=false
D [26/Jan/2016:23:33:03 +0100] [Job 58] Option: TonerSave=false
D [26/Jan/2016:23:33:03 +0100] [Job 58] Setting option
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %%BeginFeature: *Resolution 300dpi
D [26/Jan/2016:23:33:03 +0100] [Job 58] Option: Resolution=300dpi
D [26/Jan/2016:23:33:03 +0100] [Job 58] Setting option
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %% FoomaticRIPOptionSetting: Resolution=300dpi
D [26/Jan/2016:23:33:03 +0100] [Job 58] Option: Resolution=300dpi
D [26/Jan/2016:23:33:03 +0100] [Job 58] Setting option
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %%BeginFeature: *Color black
D [26/Jan/2016:23:33:03 +0100] [Job 58] Option: Color=black
D [26/Jan/2016:23:33:03 +0100] [Job 58] Setting option
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %% FoomaticRIPOptionSetting: Color=black
D [26/Jan/2016:23:33:03 +0100] [Job 58] Option: Color=black
D [26/Jan/2016:23:33:03 +0100] [Job 58] Setting option
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %%BeginFeature: *Duplex None
D [26/Jan/2016:23:33:03 +0100] [Job 58] Option: Duplex=None
D [26/Jan/2016:23:33:03 +0100] [Job 58] Setting option
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %% FoomaticRIPOptionSetting: Duplex=None
D [26/Jan/2016:23:33:03 +0100] [Job 58] Option: Duplex=None
D [26/Jan/2016:23:33:03 +0100] [Job 58] Setting option
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %%EndSetup
D [26/Jan/2016:23:33:03 +0100] [Job 58] Inserting PostScript code for CUPS' page accounting
D [26/Jan/2016:23:33:03 +0100] [Job 58] -----------
D [26/Jan/2016:23:33:03 +0100] [Job 58] New page: %%Page: 1 1
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Found: %%BeginPageSetup
D [26/Jan/2016:23:33:03 +0100] [Job 58] Inserting option code into "PageSetup" section.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Wrote 1 pages...
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] PID 30525 (pstops) exited with no errors.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] PID 30524 (gs) exited with no errors.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Flushing FIFO.
D [26/Jan/2016:23:33:03 +0100] [Job 58] PID 30517 (/usr/lib/cups/filter/pdftops) exited with no errors.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 21] Accepted from localhost (Domain)
D [26/Jan/2016:23:33:03 +0100] [Client 21] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 21] POST / HTTP/1.1
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 21] No authentication data provided.
D [26/Jan/2016:23:33:03 +0100] [Client 21] 2.0 Get-Jobs 2
D [26/Jan/2016:23:33:03 +0100] Get-Jobs ipp://localhost/printers/
D [26/Jan/2016:23:33:03 +0100] [Client 21] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [26/Jan/2016:23:33:03 +0100] [Client 21] Content-Length: 130
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 21] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=130, response=0x7f5175979420(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:03 +0100] [Client 21] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:03 +0100] [Client 21] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:03 +0100] [Client 21] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 22] Accepted from localhost (Domain)
D [26/Jan/2016:23:33:03 +0100] [Client 22] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 21] HTTP_STATE_WAITING Closing on EOF
D [26/Jan/2016:23:33:03 +0100] [Client 21] Closing connection.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 22] POST / HTTP/1.1
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 22] No authentication data provided.
D [26/Jan/2016:23:33:03 +0100] [Client 22] 2.0 Get-Notifications 25
D [26/Jan/2016:23:33:03 +0100] Get-Notifications /
D [26/Jan/2016:23:33:03 +0100] cupsdIsAuthorized: requesting-user-name="tower"
D [26/Jan/2016:23:33:03 +0100] [Client 22] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [26/Jan/2016:23:33:03 +0100] [Client 22] Content-Length: 1781
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1781, response=0x7f5175974fe0(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:03 +0100] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:03 +0100] [Client 22] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:03 +0100] [Client 22] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] HTTP_STATE_WAITING Closing on EOF
D [26/Jan/2016:23:33:03 +0100] [Client 19] Closing connection.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Starting renderer with command: "pstoalc1100.sh  Copies=1 Collate=on InputSlot=autoselection TonerSave=false MediaType=normal PageSize=a4 XY600=4960x7016 Resolution=300dpi Color=black Duplex=None"
D [26/Jan/2016:23:33:03 +0100] [Job 58] Starting process "kid3" (generation 1)
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Starting process "kid4" (generation 2)
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] Accepted from localhost (Domain)
D [26/Jan/2016:23:33:03 +0100] [Client 19] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Starting process "renderer" (generation 2)
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] JCL: %-12345X@PJL
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] <job data>
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] HTTP_STATE_WAITING Closing on EOF
D [26/Jan/2016:23:33:03 +0100] [Client 22] Closing connection.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 18] POST / HTTP/1.1
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 18] No authentication data provided.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 18] 2.0 Get-Notifications 26
D [26/Jan/2016:23:33:03 +0100] Get-Notifications /
D [26/Jan/2016:23:33:03 +0100] cupsdIsAuthorized: requesting-user-name="tower"
D [26/Jan/2016:23:33:03 +0100] [Client 18] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [26/Jan/2016:23:33:03 +0100] [Client 18] Content-Length: 1657
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1657, response=0x7f517593dd60(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:03 +0100] [Client 18] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:03 +0100] [Client 18] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:03 +0100] [Client 18] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 19] POST /printers/AL-C1100 HTTP/1.1
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 19] No authentication data provided.
D [26/Jan/2016:23:33:03 +0100] [Client 19] 2.0 Get-Printer-Attributes 27
D [26/Jan/2016:23:33:03 +0100] Get-Printer-Attributes ipp://localhost/printers/AL-C1100
D [26/Jan/2016:23:33:03 +0100] [Client 19] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/AL-C1100) from localhost
D [26/Jan/2016:23:33:03 +0100] [Client 19] Content-Length: 8924
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=8924, response=0x7f517592aa70(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:03 +0100] [Client 19] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:03 +0100] [Client 19] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:03 +0100] [Client 19] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] GET /printers/AL-C1100.ppd HTTP/1.1
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 19] No authentication data provided.
D [26/Jan/2016:23:33:03 +0100] [Client 19] Sending file.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=34561, response=(nil)(), pipe_pid=0, file=21
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=32513, response=(nil)(), pipe_pid=0, file=21
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=30465, response=(nil)(), pipe_pid=0, file=21
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=28417, response=(nil)(), pipe_pid=0, file=21
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=26369, response=(nil)(), pipe_pid=0, file=21
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=24321, response=(nil)(), pipe_pid=0, file=21
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=22273, response=(nil)(), pipe_pid=0, file=21
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20225, response=(nil)(), pipe_pid=0, file=21
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=18177, response=(nil)(), pipe_pid=0, file=21
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=16129, response=(nil)(), pipe_pid=0, file=21
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=14081, response=(nil)(), pipe_pid=0, file=21
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=12033, response=(nil)(), pipe_pid=0, file=21
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9985, response=(nil)(), pipe_pid=0, file=21
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7937, response=(nil)(), pipe_pid=0, file=21
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=5889, response=(nil)(), pipe_pid=0, file=21
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3841, response=(nil)(), pipe_pid=0, file=21
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1793, response=(nil)(), pipe_pid=0, file=21
D [26/Jan/2016:23:33:03 +0100] [Client 19] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] sh: 1: alc1100: not found
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Closing renderer
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 21] Accepted from localhost (Domain)
D [26/Jan/2016:23:33:03 +0100] [Client 21] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] Accepted from localhost (Domain)
D [26/Jan/2016:23:33:03 +0100] [Client 22] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] POST / HTTP/1.1
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 22] No authentication data provided.
D [26/Jan/2016:23:33:03 +0100] [Client 22] 2.0 Create-Printer-Subscriptions 1
D [26/Jan/2016:23:33:03 +0100] Create-Printer-Subscriptions /
D [26/Jan/2016:23:33:03 +0100] create_subscriptions(con=0x7f5175979220(22), uri="/")
D [26/Jan/2016:23:33:03 +0100] pullmethod="ippget"
D [26/Jan/2016:23:33:03 +0100] notify-lease-duration=86400
D [26/Jan/2016:23:33:03 +0100] notify-time-interval=0
D [26/Jan/2016:23:33:03 +0100] cupsdAddSubscription(mask=17b8f, dest=(nil)(), job=(nil)(0), uri="(null)")
D [26/Jan/2016:23:33:03 +0100] Added subscription #161 for server.
D [26/Jan/2016:23:33:03 +0100] cupsdMarkDirty(----S)
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 22] Returning IPP successful-ok for Create-Printer-Subscriptions (/) from localhost
D [26/Jan/2016:23:33:03 +0100] [Client 22] Content-Length: 107
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=107, response=0x7f517597d7e0(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:03 +0100] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:03 +0100] [Client 22] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:03 +0100] [Client 22] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] POST / HTTP/1.1
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 22] No authentication data provided.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] 2.0 CUPS-Get-Printers 2
D [26/Jan/2016:23:33:03 +0100] CUPS-Get-Printers
D [26/Jan/2016:23:33:03 +0100] [Client 22] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [26/Jan/2016:23:33:03 +0100] [Client 22] Content-Length: 487
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=487, response=0x7f517592aa70(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:03 +0100] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:03 +0100] [Client 22] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:03 +0100] [Client 22] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] POST / HTTP/1.1
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 22] No authentication data provided.
D [26/Jan/2016:23:33:03 +0100] [Client 22] 2.0 CUPS-Get-Printers 3
D [26/Jan/2016:23:33:03 +0100] CUPS-Get-Printers
D [26/Jan/2016:23:33:03 +0100] [Client 22] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [26/Jan/2016:23:33:03 +0100] [Client 22] Content-Length: 487
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=487, response=0x7f517597d7e0(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:03 +0100] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:03 +0100] [Client 22] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:03 +0100] [Client 22] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] HTTP_STATE_WAITING Closing on EOF
D [26/Jan/2016:23:33:03 +0100] [Client 22] Closing connection.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] Accepted from localhost (Domain)
D [26/Jan/2016:23:33:03 +0100] [Client 22] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] POST / HTTP/1.1
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 22] No authentication data provided.
D [26/Jan/2016:23:33:03 +0100] [Client 22] 2.0 Get-Printer-Attributes 4
D [26/Jan/2016:23:33:03 +0100] Get-Printer-Attributes ipp://localhost/printers/AL-C1100
D [26/Jan/2016:23:33:03 +0100] [Client 22] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/AL-C1100) from localhost
D [26/Jan/2016:23:33:03 +0100] [Client 22] Content-Length: 224
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=224, response=0x7f517597d9c0(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:03 +0100] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:03 +0100] [Client 22] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:03 +0100] [Client 22] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] HTTP_STATE_WAITING Closing on EOF
D [26/Jan/2016:23:33:03 +0100] [Client 22] Closing connection.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] Accepted from localhost (Domain)
D [26/Jan/2016:23:33:03 +0100] [Client 22] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] POST / HTTP/1.1
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 22] No authentication data provided.
D [26/Jan/2016:23:33:03 +0100] [Client 22] 2.0 Get-Jobs 5
D [26/Jan/2016:23:33:03 +0100] Get-Jobs ipp://localhost/printers/
D [26/Jan/2016:23:33:03 +0100] [Client 22] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [26/Jan/2016:23:33:03 +0100] [Client 22] Content-Length: 269
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=269, response=0x7f517597d7f0(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:03 +0100] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:03 +0100] [Client 22] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:03 +0100] [Client 22] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 23] Accepted from localhost (Domain)
D [26/Jan/2016:23:33:03 +0100] [Client 23] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 23] POST / HTTP/1.1
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 23] No authentication data provided.
D [26/Jan/2016:23:33:03 +0100] [Client 23] 2.0 Get-Job-Attributes 6
D [26/Jan/2016:23:33:03 +0100] Get-Job-Attributes ipp://localhost/jobs/58
D [26/Jan/2016:23:33:03 +0100] [Client 23] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/58) from localhost
D [26/Jan/2016:23:33:03 +0100] [Client 23] Content-Length: 75
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f517597d600(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:03 +0100] [Client 23] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:03 +0100] [Client 23] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:03 +0100] [Client 23] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 23] HTTP_STATE_WAITING Closing on EOF
D [26/Jan/2016:23:33:03 +0100] [Client 23] Closing connection.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] HTTP_STATE_WAITING Closing on EOF
D [26/Jan/2016:23:33:03 +0100] [Client 22] Closing connection.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] Accepted from localhost (Domain)
D [26/Jan/2016:23:33:03 +0100] [Client 22] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] POST / HTTP/1.1
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 22] No authentication data provided.
D [26/Jan/2016:23:33:03 +0100] [Client 22] 2.0 Get-Jobs 7
D [26/Jan/2016:23:33:03 +0100] Get-Jobs ipp://localhost/printers/
D [26/Jan/2016:23:33:03 +0100] [Client 22] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [26/Jan/2016:23:33:03 +0100] [Client 22] Content-Length: 75
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f517597d7f0(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:03 +0100] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:03 +0100] [Client 22] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:03 +0100] [Client 22] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] HTTP_STATE_WAITING Closing on EOF
D [26/Jan/2016:23:33:03 +0100] [Client 22] Closing connection.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] PAGE: 1 1
D [26/Jan/2016:23:33:03 +0100] cupsdMarkDirty(----S)
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] renderer exited with status 0
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] kid4 exited with status 0
D [26/Jan/2016:23:33:03 +0100] [Job 58] kid3 finished
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] kid3 exited with status 0
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Closing foomatic-rip.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] PID 30518 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] Sent 0 bytes...
D [26/Jan/2016:23:33:03 +0100] [Job 58] Waiting for read thread to exit...
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] Accepted from localhost (Domain)
D [26/Jan/2016:23:33:03 +0100] [Client 22] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] POST / HTTP/1.1
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 22] No authentication data provided.
D [26/Jan/2016:23:33:03 +0100] [Client 22] 2.0 CUPS-Get-Printers 28
D [26/Jan/2016:23:33:03 +0100] CUPS-Get-Printers
D [26/Jan/2016:23:33:03 +0100] [Client 22] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [26/Jan/2016:23:33:03 +0100] [Client 22] Content-Length: 487
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=487, response=0x7f517597af30(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:03 +0100] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:03 +0100] [Client 22] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:03 +0100] [Client 22] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] POST / HTTP/1.1
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 22] No authentication data provided.
D [26/Jan/2016:23:33:03 +0100] [Client 22] 2.0 CUPS-Get-Classes 29
D [26/Jan/2016:23:33:03 +0100] CUPS-Get-Classes
D [26/Jan/2016:23:33:03 +0100] [Client 22] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [26/Jan/2016:23:33:03 +0100] [Client 22] Content-Length: 75
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f517597ad90(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:03 +0100] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:03 +0100] [Client 22] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:03 +0100] [Client 22] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] POST / HTTP/1.1
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] [Client 22] No authentication data provided.
D [26/Jan/2016:23:33:03 +0100] [Client 22] 2.0 CUPS-Get-Default 30
D [26/Jan/2016:23:33:03 +0100] CUPS-Get-Default
D [26/Jan/2016:23:33:03 +0100] [Client 22] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [26/Jan/2016:23:33:03 +0100] [Client 22] Content-Length: 8924
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=8924, response=0x7f517597af30(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:03 +0100] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:03 +0100] [Client 22] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:03 +0100] [Client 22] Waiting for request.
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] PID 30519 (/usr/lib/cups/backend/usb) exited with no errors.
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:03 +0100] [Job 58] time-at-completed=1453847583
D [26/Jan/2016:23:33:03 +0100] cupsdMarkDirty(----S)
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
I [26/Jan/2016:23:33:03 +0100] [Job 58] Job completed.
D [26/Jan/2016:23:33:03 +0100] cupsdMarkDirty(---J-)
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsdMarkDirty(----S)
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsdMarkDirty(---J-)
D [26/Jan/2016:23:33:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:03 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 20] Accepted from localhost (Domain)
D [26/Jan/2016:23:33:04 +0100] [Client 20] Waiting for request.
D [26/Jan/2016:23:33:04 +0100] [Job 58] Unloading...
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 23] Accepted from localhost (Domain)
D [26/Jan/2016:23:33:04 +0100] [Client 23] Waiting for request.
D [26/Jan/2016:23:33:04 +0100] [Client 20] POST / HTTP/1.1
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"
D [26/Jan/2016:23:33:04 +0100] [Client 20] No authentication data provided.
D [26/Jan/2016:23:33:04 +0100] [Client 20] 2.0 Get-Notifications 31
D [26/Jan/2016:23:33:04 +0100] Get-Notifications /
D [26/Jan/2016:23:33:04 +0100] cupsdIsAuthorized: requesting-user-name="tower"
D [26/Jan/2016:23:33:04 +0100] [Client 20] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [26/Jan/2016:23:33:04 +0100] [Client 20] Content-Length: 541
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=541, response=0x7f517592b100(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:04 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:04 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:04 +0100] [Client 20] Waiting for request.
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:33:04 +0100] [Client 23] POST / HTTP/1.1
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:04 +0100] [Client 23] No authentication data provided.
D [26/Jan/2016:23:33:04 +0100] [Client 23] 2.0 Get-Notifications 8
D [26/Jan/2016:23:33:04 +0100] Get-Notifications /
D [26/Jan/2016:23:33:04 +0100] cupsdIsAuthorized: requesting-user-name="tower"
D [26/Jan/2016:23:33:04 +0100] [Client 23] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [26/Jan/2016:23:33:04 +0100] [Client 23] Content-Length: 1576
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1576, response=0x7f5175923e30(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:04 +0100] [Client 23] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:04 +0100] [Client 23] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:04 +0100] [Client 23] Waiting for request.
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 20] HTTP_STATE_WAITING Closing on EOF
D [26/Jan/2016:23:33:04 +0100] [Client 20] Closing connection.
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 20] Accepted from localhost (Domain)
D [26/Jan/2016:23:33:04 +0100] [Client 20] Waiting for request.
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 20] POST / HTTP/1.1
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:04 +0100] [Client 20] No authentication data provided.
D [26/Jan/2016:23:33:04 +0100] [Client 20] 2.0 Get-Printer-Attributes 9
D [26/Jan/2016:23:33:04 +0100] Get-Printer-Attributes ipp://TOWER:631/printers/AL-C1100
D [26/Jan/2016:23:33:04 +0100] [Client 20] Returning IPP successful-ok for Get-Printer-Attributes (ipp://TOWER:631/printers/AL-C1100) from localhost
D [26/Jan/2016:23:33:04 +0100] [Client 20] Content-Length: 224
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=224, response=0x7f517592a950(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:04 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:04 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:04 +0100] [Client 20] Waiting for request.
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:33:04 +0100] [Client 18] POST / HTTP/1.1
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:04 +0100] [Client 18] No authentication data provided.
D [26/Jan/2016:23:33:04 +0100] [Client 18] 2.0 Get-Notifications 32
D [26/Jan/2016:23:33:04 +0100] Get-Notifications /
D [26/Jan/2016:23:33:04 +0100] cupsdIsAuthorized: requesting-user-name="tower"
D [26/Jan/2016:23:33:04 +0100] [Client 18] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [26/Jan/2016:23:33:04 +0100] [Client 18] Content-Length: 1162
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1162, response=0x7f51758def00(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:04 +0100] [Client 18] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:04 +0100] [Client 18] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:04 +0100] [Client 18] Waiting for request.
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 20] HTTP_STATE_WAITING Closing on EOF
D [26/Jan/2016:23:33:04 +0100] [Client 20] Closing connection.
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 20] Accepted from localhost (Domain)
D [26/Jan/2016:23:33:04 +0100] [Client 20] Waiting for request.
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 20] POST / HTTP/1.1
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:04 +0100] [Client 20] No authentication data provided.
D [26/Jan/2016:23:33:04 +0100] [Client 20] 2.0 Get-Job-Attributes 10
D [26/Jan/2016:23:33:04 +0100] Get-Job-Attributes ipp://localhost/jobs/58
D [26/Jan/2016:23:33:04 +0100] [Job 58] Loading attributes...
D [26/Jan/2016:23:33:04 +0100] [Client 20] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/58) from localhost
D [26/Jan/2016:23:33:04 +0100] [Client 20] Content-Length: 95
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=95, response=0x7f51758dfdc0(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:04 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:04 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:04 +0100] [Client 20] Waiting for request.
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 20] HTTP_STATE_WAITING Closing on EOF
D [26/Jan/2016:23:33:04 +0100] [Client 20] Closing connection.
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 20] Accepted from localhost (Domain)
D [26/Jan/2016:23:33:04 +0100] [Client 20] Waiting for request.
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 20] POST / HTTP/1.1
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:04 +0100] [Client 20] No authentication data provided.
D [26/Jan/2016:23:33:04 +0100] [Client 20] 2.0 Get-Printer-Attributes 11
D [26/Jan/2016:23:33:04 +0100] Get-Printer-Attributes ipp://TOWER:631/printers/AL-C1100
D [26/Jan/2016:23:33:04 +0100] [Client 20] Returning IPP successful-ok for Get-Printer-Attributes (ipp://TOWER:631/printers/AL-C1100) from localhost
D [26/Jan/2016:23:33:04 +0100] [Client 20] Content-Length: 137
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=137, response=0x7f51758def00(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:04 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:04 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:04 +0100] [Client 20] Waiting for request.
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 23] HTTP_STATE_WAITING Closing on EOF
D [26/Jan/2016:23:33:04 +0100] [Client 23] Closing connection.
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 22] POST / HTTP/1.1
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:04 +0100] [Client 22] No authentication data provided.
D [26/Jan/2016:23:33:04 +0100] [Client 22] 2.0 CUPS-Get-Printers 33
D [26/Jan/2016:23:33:04 +0100] CUPS-Get-Printers
D [26/Jan/2016:23:33:04 +0100] [Client 22] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [26/Jan/2016:23:33:04 +0100] [Client 22] Content-Length: 487
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=487, response=0x7f5175971ed0(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:04 +0100] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:04 +0100] [Client 22] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:04 +0100] [Client 22] Waiting for request.
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 22] POST / HTTP/1.1
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:04 +0100] [Client 22] No authentication data provided.
D [26/Jan/2016:23:33:04 +0100] [Client 22] 2.0 CUPS-Get-Classes 34
D [26/Jan/2016:23:33:04 +0100] CUPS-Get-Classes
D [26/Jan/2016:23:33:04 +0100] [Client 22] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [26/Jan/2016:23:33:04 +0100] [Client 22] Content-Length: 75
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f51758def00(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:04 +0100] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:04 +0100] [Client 22] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:04 +0100] [Client 22] Waiting for request.
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 22] POST / HTTP/1.1
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:04 +0100] [Client 22] No authentication data provided.
D [26/Jan/2016:23:33:04 +0100] [Client 22] 2.0 CUPS-Get-Default 35
D [26/Jan/2016:23:33:04 +0100] CUPS-Get-Default
D [26/Jan/2016:23:33:04 +0100] [Client 22] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [26/Jan/2016:23:33:04 +0100] [Client 22] Content-Length: 8924
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:04 +0100] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=8924, response=0x7f5175971ed0(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:04 +0100] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:04 +0100] [Client 22] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:04 +0100] [Client 22] Waiting for request.
D [26/Jan/2016:23:33:04 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:33:04 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:05 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:20 +0100] [Client 18] POST / HTTP/1.1
D [26/Jan/2016:23:33:20 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:20 +0100] [Client 18] No authentication data provided.
D [26/Jan/2016:23:33:20 +0100] [Client 18] 2.0 Get-Job-Attributes 36
D [26/Jan/2016:23:33:20 +0100] Get-Job-Attributes ipp://localhost/jobs/58
D [26/Jan/2016:23:33:20 +0100] [Client 18] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/58) from localhost
D [26/Jan/2016:23:33:20 +0100] [Client 18] Content-Length: 830
D [26/Jan/2016:23:33:20 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:20 +0100] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=830, response=0x7f51758def00(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:20 +0100] [Client 18] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:20 +0100] [Client 18] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:20 +0100] [Client 18] Waiting for request.
D [26/Jan/2016:23:33:20 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:33:20 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:20 +0100] [Client 18] POST / HTTP/1.1
D [26/Jan/2016:23:33:20 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:20 +0100] [Client 18] No authentication data provided.
D [26/Jan/2016:23:33:20 +0100] [Client 18] 2.0 Cancel-Subscription 37
D [26/Jan/2016:23:33:20 +0100] Cancel-Subscription /
D [26/Jan/2016:23:33:20 +0100] cupsdIsAuthorized: requesting-user-name="tower"
D [26/Jan/2016:23:33:20 +0100] cupsdMarkDirty(----S)
D [26/Jan/2016:23:33:20 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:33:20 +0100] [Client 18] Returning IPP successful-ok for Cancel-Subscription (/) from localhost
D [26/Jan/2016:23:33:20 +0100] [Client 18] Content-Length: 75
D [26/Jan/2016:23:33:20 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:20 +0100] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f5175971ed0(IPP_IDLE), pipe_pid=0, file=-1
D [26/Jan/2016:23:33:20 +0100] [Client 18] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [26/Jan/2016:23:33:20 +0100] [Client 18] bytes=0, http_state=0, data_remaining=0
D [26/Jan/2016:23:33:20 +0100] [Client 18] Waiting for request.
D [26/Jan/2016:23:33:20 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:33:20 +0100] cupsd is not idle any more, canceling shutdown.
D [26/Jan/2016:23:33:20 +0100] [Client 17] PUT /admin/conf/cupsd.conf HTTP/1.1
D [26/Jan/2016:23:33:20 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:20 +0100] [Client 17] Authorized as root using PeerCred
D [26/Jan/2016:23:33:20 +0100] cupsdIsAuthorized: username="root"
D [26/Jan/2016:23:33:20 +0100] cupsd is not idle any more, canceling shutdown.
I [26/Jan/2016:23:33:20 +0100] Installing config file "/etc/cups/cupsd.conf"...
D [26/Jan/2016:23:33:20 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [26/Jan/2016:23:33:20 +0100] [Client 17] Closing connection.
D [26/Jan/2016:23:33:20 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:20 +0100] [Client 18] Closing connection.
D [26/Jan/2016:23:33:20 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:20 +0100] [Client 19] Closing connection.
D [26/Jan/2016:23:33:20 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:20 +0100] [Client 21] Closing connection.
D [26/Jan/2016:23:33:20 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:20 +0100] [Client 22] Closing connection.
D [26/Jan/2016:23:33:20 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [26/Jan/2016:23:33:20 +0100] [Client 20] Closing connection.
D [26/Jan/2016:23:33:20 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [26/Jan/2016:23:33:20 +0100] Generating printcap /var/run/cups/printcap...
I [26/Jan/2016:23:33:20 +0100] Saving job.cache...
I [26/Jan/2016:23:33:20 +0100] Saving subscriptions.conf...
D [26/Jan/2016:23:33:20 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
E [26/Jan/2016:23:33:20 +0100] Unknown directive JobPrivateAccess on line 85 of /etc/cups/cupsd.conf.
E [26/Jan/2016:23:33:20 +0100] Unknown directive JobPrivateValues on line 86 of /etc/cups/cupsd.conf.
E [26/Jan/2016:23:33:20 +0100] Unknown directive SubscriptionPrivateAccess on line 87 of /etc/cups/cupsd.conf.
E [26/Jan/2016:23:33:20 +0100] Unknown directive SubscriptionPrivateValues on line 88 of /etc/cups/cupsd.conf.
W [26/Jan/2016:23:33:20 +0100] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'AL-C1100-Gray..' already exists
W [26/Jan/2016:23:33:20 +0100] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'AL-C1100-RGB..' already exists