W [02/Jul/2017:09:47:19 +0100] Notifier for subscription 2 (dbus://) went away, retrying!
W [02/Jul/2017:09:47:19 +0100] Notifier for subscription 4 (dbus://) went away, retrying!
W [02/Jul/2017:10:02:27 +0100] Notifier for subscription 2 (dbus://) went away, retrying!
W [02/Jul/2017:10:02:27 +0100] Notifier for subscription 4 (dbus://) went away, retrying!
I [02/Jul/2017:10:11:25 +0100] Listening to [v1.::1]:631 (IPv6)
I [02/Jul/2017:10:11:25 +0100] Listening to 127.0.0.1:631 (IPv4)
I [02/Jul/2017:10:11:25 +0100] Remote access is disabled.
D [02/Jul/2017:10:11:25 +0100] Added auto ServerAlias TILL2
I [02/Jul/2017:10:11:25 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
D [02/Jul/2017:10:11:25 +0100] Using keychain "/etc/cups/ssl" for server name "TILL2".
I [02/Jul/2017:10:11:25 +0100] Configured for up to 100 clients.
I [02/Jul/2017:10:11:25 +0100] Allowing up to 100 client connections per host.
I [02/Jul/2017:10:11:25 +0100] Using policy "default" as the default.
D [02/Jul/2017:10:11:25 +0100] cupsdMarkDirty(P----)
D [02/Jul/2017:10:11:25 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Not busy"
D [02/Jul/2017:10:11:25 +0100] load_ppd: Loading /etc/cups/ppd/POS-80-Series.ppd...
D [02/Jul/2017:10:11:25 +0100] cupsdRegisterPrinter(p=0x55b93b931300(POS-80-Series))
D [02/Jul/2017:10:11:25 +0100] cupsdMarkDirty(--p--)
D [02/Jul/2017:10:11:25 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [02/Jul/2017:10:11:25 +0100] Partial reload complete.
D [02/Jul/2017:10:11:25 +0100] Calling FindDeviceById(cups-POS-80-Series)
D [02/Jul/2017:10:11:25 +0100] FindDeviceById failed: org.freedesktop.ColorManager.NotFound:device id \'cups-POS-80-Series\' does not exist
I [02/Jul/2017:10:11:25 +0100] Listening to /var/run/cups/cups.sock on fd 3...
I [02/Jul/2017:10:11:25 +0100] Listening to [v1.::1]:631 on fd 10...
I [02/Jul/2017:10:11:25 +0100] Listening to 127.0.0.1:631 on fd 11...
I [02/Jul/2017:10:11:25 +0100] Resuming new connection processing...
D [02/Jul/2017:10:11:25 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [02/Jul/2017:10:11:25 +0100] Notifier dbus started - PID = 3075
D [02/Jul/2017:10:11:25 +0100] Notifier dbus started - PID = 3076
D [02/Jul/2017:10:11:25 +0100] cupsdMarkDirty(----S)
D [02/Jul/2017:10:11:25 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [02/Jul/2017:10:11:25 +0100] [Notifier] state=3
D [02/Jul/2017:10:11:25 +0100] Report: clients=0
D [02/Jul/2017:10:11:25 +0100] Report: jobs=2
D [02/Jul/2017:10:11:25 +0100] Report: jobs-active=0
D [02/Jul/2017:10:11:25 +0100] Report: printers=1
D [02/Jul/2017:10:11:25 +0100] Report: stringpool-string-count=1839
D [02/Jul/2017:10:11:25 +0100] Report: stringpool-alloc-bytes=5864
D [02/Jul/2017:10:11:25 +0100] Report: stringpool-total-bytes=35864
D [02/Jul/2017:10:11:25 +0100] PID 2951 (/usr/lib/cups/notifier/dbus) exited with no errors.
D [02/Jul/2017:10:11:25 +0100] PID 2952 (/usr/lib/cups/notifier/dbus) was terminated normally with signal 15.
D [02/Jul/2017:10:11:25 +0100] [Notifier] Connected to D-BUS
D [02/Jul/2017:10:11:25 +0100] [Notifier] ServerRestarted
D [02/Jul/2017:10:11:25 +0100] [Notifier] state=3
D [02/Jul/2017:10:11:25 +0100] [Notifier] Connected to D-BUS
D [02/Jul/2017:10:11:26 +0100] [Client 134] Accepted from localhost (Domain)
D [02/Jul/2017:10:11:26 +0100] [Client 134] Waiting for request.
I [02/Jul/2017:10:11:26 +0100] Expiring subscriptions...
D [02/Jul/2017:10:11:26 +0100] cupsdAddCert: Adding certificate for PID 0
I [02/Jul/2017:10:11:27 +0100] Expiring subscriptions...
D [02/Jul/2017:10:11:31 +0100] [Client 135] Accepted from localhost (Domain)
D [02/Jul/2017:10:11:31 +0100] [Client 135] Waiting for request.
I [02/Jul/2017:10:11:31 +0100] Expiring subscriptions...
D [02/Jul/2017:10:11:31 +0100] [Client 135] POST / HTTP/1.1
D [02/Jul/2017:10:11:31 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [02/Jul/2017:10:11:31 +0100] [Client 135] Read: status=200
D [02/Jul/2017:10:11:31 +0100] [Client 135] No authentication data provided.
D [02/Jul/2017:10:11:31 +0100] [Client 135] 2.0 Cancel-Subscription 122
D [02/Jul/2017:10:11:31 +0100] Cancel-Subscription /
D [02/Jul/2017:10:11:31 +0100] cupsdIsAuthorized: requesting-user-name="till2"
D [02/Jul/2017:10:11:31 +0100] cupsdMarkDirty(----S)
D [02/Jul/2017:10:11:31 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [02/Jul/2017:10:11:31 +0100] [Client 135] Returning IPP successful-ok for Cancel-Subscription (/) from localhost
D [02/Jul/2017:10:11:31 +0100] [Client 135] Content-Length: 75
D [02/Jul/2017:10:11:31 +0100] [Client 135] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [02/Jul/2017:10:11:31 +0100] [Client 135] con->http=0x55b93b978f10
D [02/Jul/2017:10:11:31 +0100] [Client 135] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55b93b958ac0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [02/Jul/2017:10:11:31 +0100] [Client 135] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [02/Jul/2017:10:11:31 +0100] [Client 135] bytes=0, http_state=0, data_remaining=75
D [02/Jul/2017:10:11:31 +0100] [Client 135] Flushing write buffer.
D [02/Jul/2017:10:11:31 +0100] [Client 135] New state is HTTP_STATE_WAITING
D [02/Jul/2017:10:11:31 +0100] [Client 135] Waiting for request.
D [02/Jul/2017:10:11:31 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [02/Jul/2017:10:11:31 +0100] [Client 135] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:11:31 +0100] [Client 135] Closing connection.
D [02/Jul/2017:10:11:31 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [02/Jul/2017:10:11:31 +0100] [Client 134] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:11:31 +0100] [Client 134] Closing connection.
D [02/Jul/2017:10:11:31 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [02/Jul/2017:10:11:32 +0100] Expiring subscriptions...
D [02/Jul/2017:10:11:40 +0100] [Client 136] Accepted from localhost:47468 (IPv6)
D [02/Jul/2017:10:11:40 +0100] [Client 136] Waiting for request.
I [02/Jul/2017:10:11:40 +0100] Expiring subscriptions...
D [02/Jul/2017:10:11:40 +0100] [Client 136] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:11:40 +0100] [Client 136] Closing connection.
D [02/Jul/2017:10:11:40 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [02/Jul/2017:10:11:41 +0100] Expiring subscriptions...
D [02/Jul/2017:10:11:46 +0100] [Client 137] Accepted from localhost:47470 (IPv6)
D [02/Jul/2017:10:11:46 +0100] [Client 137] Waiting for request.
I [02/Jul/2017:10:11:46 +0100] Expiring subscriptions...
D [02/Jul/2017:10:11:46 +0100] [Client 137] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:11:46 +0100] [Client 137] Closing connection.
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [02/Jul/2017:10:11:46 +0100] [Client 138] Accepted from localhost:47472 (IPv6)
D [02/Jul/2017:10:11:46 +0100] [Client 138] Waiting for request.
D [02/Jul/2017:10:11:46 +0100] [Client 138] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:11:46 +0100] [Client 138] Closing connection.
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [02/Jul/2017:10:11:46 +0100] [Client 139] Accepted from localhost (Domain)
D [02/Jul/2017:10:11:46 +0100] [Client 139] Waiting for request.
D [02/Jul/2017:10:11:46 +0100] [Client 139] POST / HTTP/1.1
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [02/Jul/2017:10:11:46 +0100] [Client 139] Read: status=200
D [02/Jul/2017:10:11:46 +0100] [Client 139] No authentication data provided.
D [02/Jul/2017:10:11:46 +0100] [Client 139] 2.0 Get-Printer-Attributes 1
D [02/Jul/2017:10:11:46 +0100] Get-Printer-Attributes ipp://localhost:631/printers/POS-80-Series
D [02/Jul/2017:10:11:46 +0100] [Client 139] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/POS-80-Series) from localhost
D [02/Jul/2017:10:11:46 +0100] [Client 139] Content-Length: 778
D [02/Jul/2017:10:11:46 +0100] [Client 139] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [02/Jul/2017:10:11:46 +0100] [Client 139] con->http=0x55b93b9223d0
D [02/Jul/2017:10:11:46 +0100] [Client 139] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=778, response=0x55b93b958cf0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [02/Jul/2017:10:11:46 +0100] [Client 139] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [02/Jul/2017:10:11:46 +0100] [Client 139] bytes=0, http_state=0, data_remaining=778
D [02/Jul/2017:10:11:46 +0100] [Client 139] Flushing write buffer.
D [02/Jul/2017:10:11:46 +0100] [Client 139] New state is HTTP_STATE_WAITING
D [02/Jul/2017:10:11:46 +0100] [Client 139] Waiting for request.
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [02/Jul/2017:10:11:46 +0100] [Client 139] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:11:46 +0100] [Client 139] Closing connection.
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [02/Jul/2017:10:11:46 +0100] [Client 140] Accepted from localhost (Domain)
D [02/Jul/2017:10:11:46 +0100] [Client 140] Waiting for request.
D [02/Jul/2017:10:11:46 +0100] [Client 140] POST /printers/POS-80-Series HTTP/1.1
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [02/Jul/2017:10:11:46 +0100] [Client 140] Read: status=200
D [02/Jul/2017:10:11:46 +0100] [Client 140] No authentication data provided.
D [02/Jul/2017:10:11:46 +0100] [Client 140] 2.0 Create-Job 2
D [02/Jul/2017:10:11:46 +0100] Create-Job ipp://localhost:631/printers/POS-80-Series
D [02/Jul/2017:10:11:46 +0100] cupsdMarkDirty(---J-)
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [02/Jul/2017:10:11:46 +0100] add_job: requesting-user-name="till2"
I [02/Jul/2017:10:11:46 +0100] [Job 5] Adding start banner page "none".
D [02/Jul/2017:10:11:46 +0100] cupsdMarkDirty(----S)
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [02/Jul/2017:10:11:46 +0100] [Job 5] Queued on "POS-80-Series" by "till2".
D [02/Jul/2017:10:11:46 +0100] [Client 140] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/POS-80-Series) from localhost
D [02/Jul/2017:10:11:46 +0100] [Client 140] Content-Length: 199
D [02/Jul/2017:10:11:46 +0100] [Client 140] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [02/Jul/2017:10:11:46 +0100] [Client 140] con->http=0x55b93b9223d0
D [02/Jul/2017:10:11:46 +0100] [Client 140] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=199, response=0x55b93b958b50(IPP_STATE_IDLE), pipe_pid=0, file=-1
D [02/Jul/2017:10:11:46 +0100] [Client 140] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [02/Jul/2017:10:11:46 +0100] [Client 140] bytes=0, http_state=0, data_remaining=199
D [02/Jul/2017:10:11:46 +0100] [Client 140] Flushing write buffer.
D [02/Jul/2017:10:11:46 +0100] [Client 140] New state is HTTP_STATE_WAITING
D [02/Jul/2017:10:11:46 +0100] [Client 140] Waiting for request.
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [02/Jul/2017:10:11:46 +0100] [Notifier] state=3
D [02/Jul/2017:10:11:46 +0100] [Notifier] JobCreated
D [02/Jul/2017:10:11:46 +0100] [Notifier] state=3
D [02/Jul/2017:10:11:46 +0100] [Client 140] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:11:46 +0100] [Client 140] Closing connection.
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [02/Jul/2017:10:11:46 +0100] [Client 141] Accepted from localhost (Domain)
D [02/Jul/2017:10:11:46 +0100] [Client 141] Waiting for request.
D [02/Jul/2017:10:11:46 +0100] [Client 141] POST /printers/POS-80-Series HTTP/1.1
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=200
D [02/Jul/2017:10:11:46 +0100] [Client 141] No authentication data provided.
D [02/Jul/2017:10:11:46 +0100] [Client 141] 2.0 Send-Document 3
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] [Client 141] Read: status=100
D [02/Jul/2017:10:11:46 +0100] Send-Document ipp://localhost:631/printers/POS-80-Series
D [02/Jul/2017:10:11:46 +0100] cupsdIsAuthorized: requesting-user-name="till2"
D [02/Jul/2017:10:11:46 +0100] [Job 5] Auto-typing file...
D [02/Jul/2017:10:11:46 +0100] [Job 5] Request file type is application/postscript.
D [02/Jul/2017:10:11:46 +0100] cupsdMarkDirty(---J-)
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [02/Jul/2017:10:11:46 +0100] [Job 5] File of type application/postscript queued by "till2".
I [02/Jul/2017:10:11:46 +0100] [Job 5] Adding end banner page "none".
D [02/Jul/2017:10:11:46 +0100] cupsdMarkDirty(---J-)
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [02/Jul/2017:10:11:46 +0100] [Job 5] time-at-processing=1498986706
D [02/Jul/2017:10:11:46 +0100] cupsdMarkDirty(---J-)
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [02/Jul/2017:10:11:46 +0100] cupsdMarkDirty(----S)
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [02/Jul/2017:10:11:46 +0100] [Job 5] Sending job to queue tagged as raw...
D [02/Jul/2017:10:11:46 +0100] [Job 5] job-sheets=none,none
D [02/Jul/2017:10:11:46 +0100] [Job 5] argv[0]="POS-80-Series"
D [02/Jul/2017:10:11:46 +0100] [Job 5] argv[1]="5"
D [02/Jul/2017:10:11:46 +0100] [Job 5] argv[2]="till2"
D [02/Jul/2017:10:11:46 +0100] [Job 5] argv[3]="uniCenta oPOS - Document"
D [02/Jul/2017:10:11:46 +0100] [Job 5] argv[4]="1"
D [02/Jul/2017:10:11:46 +0100] [Job 5] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:7cb23638-eea0-3eb3-7c55-b9504516bdd0 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1498986706 time-at-processing=1498986706 document-name-supplied=javaprint5399757348115528161.ps"
D [02/Jul/2017:10:11:46 +0100] [Job 5] argv[6]="/var/spool/cups/d00005-001"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[8]="HOME=/var/spool/cups/tmp"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[10]="SERVER_ADMIN=root@TILL2"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[11]="SOFTWARE=CUPS/2.1.3"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[13]="USER=root"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[14]="CUPS_MAX_MESSAGE=2047"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[17]="IPP_PORT=631"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[18]="CHARSET=utf-8"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[19]="LANG=en_GB.UTF-8"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[20]="PPD=/etc/cups/ppd/POS-80-Series.ppd"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[21]="RIP_MAX_CACHE=128m"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[22]="CONTENT_TYPE=application/postscript"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[23]="DEVICE_URI=file:///dev/null"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[24]="PRINTER_INFO=POS-80-Series"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[25]="PRINTER_LOCATION="
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[26]="PRINTER=POS-80-Series"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[27]="PRINTER_STATE_REASONS=none"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[28]="CUPS_FILETYPE=document"
D [02/Jul/2017:10:11:46 +0100] [Job 5] envp[29]="AUTH_I****"
D [02/Jul/2017:10:11:46 +0100] cupsdMarkDirty(----S)
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
D [02/Jul/2017:10:11:46 +0100] [Client 141] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/POS-80-Series) from localhost
D [02/Jul/2017:10:11:46 +0100] [Client 141] Content-Length: 169
D [02/Jul/2017:10:11:46 +0100] [Client 141] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [02/Jul/2017:10:11:46 +0100] [Client 141] con->http=0x55b93b9223d0
D [02/Jul/2017:10:11:46 +0100] [Client 141] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=169, response=0x55b93b9272c0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [02/Jul/2017:10:11:46 +0100] [Client 141] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [02/Jul/2017:10:11:46 +0100] [Client 141] bytes=0, http_state=0, data_remaining=169
D [02/Jul/2017:10:11:46 +0100] [Client 141] Flushing write buffer.
D [02/Jul/2017:10:11:46 +0100] [Client 141] New state is HTTP_STATE_WAITING
D [02/Jul/2017:10:11:46 +0100] [Client 141] Waiting for request.
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [02/Jul/2017:10:11:46 +0100] [Notifier] state=3
D [02/Jul/2017:10:11:46 +0100] [Notifier] state=3
D [02/Jul/2017:10:11:46 +0100] [Notifier] PrinterStateChanged
D [02/Jul/2017:10:11:46 +0100] [Notifier] state=3
D [02/Jul/2017:10:11:46 +0100] [Notifier] JobState
D [02/Jul/2017:10:11:46 +0100] [Notifier] state=3
D [02/Jul/2017:10:11:46 +0100] [Job 5] time-at-completed=1498986706
D [02/Jul/2017:10:11:46 +0100] cupsdMarkDirty(----S)
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
I [02/Jul/2017:10:11:46 +0100] [Job 5] Job completed.
I [02/Jul/2017:10:11:46 +0100] Expiring subscriptions...
D [02/Jul/2017:10:11:46 +0100] cupsdMarkDirty(---J-)
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [02/Jul/2017:10:11:46 +0100] cupsdMarkDirty(----S)
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [02/Jul/2017:10:11:46 +0100] cupsdMarkDirty(---J-)
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [02/Jul/2017:10:11:46 +0100] cupsdMarkDirty(---J-)
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [02/Jul/2017:10:11:46 +0100] [Job 5] Removing document files.
D [02/Jul/2017:10:11:46 +0100] cupsdMarkDirty(---J-)
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
D [02/Jul/2017:10:11:46 +0100] [Client 141] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:11:46 +0100] [Client 141] Closing connection.
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [02/Jul/2017:10:11:46 +0100] [Notifier] state=3
D [02/Jul/2017:10:11:46 +0100] [Notifier] JobCompleted
D [02/Jul/2017:10:11:46 +0100] [Notifier] state=3
D [02/Jul/2017:10:11:46 +0100] [Notifier] state=3
D [02/Jul/2017:10:11:46 +0100] [Notifier] PrinterStateChanged
D [02/Jul/2017:10:11:46 +0100] [Notifier] state=3
D [02/Jul/2017:10:11:46 +0100] [Client 142] Accepted from localhost (Domain)
D [02/Jul/2017:10:11:46 +0100] [Client 142] Waiting for request.
D [02/Jul/2017:10:11:46 +0100] [Client 142] POST / HTTP/1.1
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [02/Jul/2017:10:11:46 +0100] [Client 142] Read: status=200
D [02/Jul/2017:10:11:46 +0100] [Client 142] No authentication data provided.
D [02/Jul/2017:10:11:46 +0100] [Client 142] 2.0 Get-Jobs 8
D [02/Jul/2017:10:11:46 +0100] Get-Jobs ipp://localhost/printers/
D [02/Jul/2017:10:11:46 +0100] [Client 142] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [02/Jul/2017:10:11:46 +0100] [Client 142] Content-Length: 75
D [02/Jul/2017:10:11:46 +0100] [Client 142] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [02/Jul/2017:10:11:46 +0100] [Client 142] con->http=0x55b93b9223d0
D [02/Jul/2017:10:11:46 +0100] [Client 142] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55b93b979120(IPP_STATE_DATA), pipe_pid=0, file=-1
D [02/Jul/2017:10:11:46 +0100] [Client 142] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [02/Jul/2017:10:11:46 +0100] [Client 142] bytes=0, http_state=0, data_remaining=75
D [02/Jul/2017:10:11:46 +0100] [Client 142] Flushing write buffer.
D [02/Jul/2017:10:11:46 +0100] [Client 142] New state is HTTP_STATE_WAITING
D [02/Jul/2017:10:11:46 +0100] [Client 142] Waiting for request.
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [02/Jul/2017:10:11:46 +0100] [Client 142] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:11:46 +0100] [Client 142] Closing connection.
D [02/Jul/2017:10:11:46 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [02/Jul/2017:10:11:47 +0100] Expiring subscriptions...
D [02/Jul/2017:10:11:47 +0100] [Job 5] Unloading...
D [02/Jul/2017:10:11:49 +0100] [Client 143] Accepted from localhost:47474 (IPv6)
D [02/Jul/2017:10:11:49 +0100] [Client 143] Waiting for request.
I [02/Jul/2017:10:11:49 +0100] Expiring subscriptions...
D [02/Jul/2017:10:11:49 +0100] [Client 143] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:11:49 +0100] [Client 143] Closing connection.
D [02/Jul/2017:10:11:49 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [02/Jul/2017:10:11:50 +0100] Expiring subscriptions...
I [02/Jul/2017:10:11:56 +0100] Saving printers.conf...
I [02/Jul/2017:10:11:56 +0100] Generating printcap /var/run/cups/printcap...
I [02/Jul/2017:10:11:56 +0100] Saving job.cache...
I [02/Jul/2017:10:11:56 +0100] Saving subscriptions.conf...
D [02/Jul/2017:10:11:56 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
I [02/Jul/2017:10:11:56 +0100] Expiring subscriptions...
D [02/Jul/2017:10:13:24 +0100] [Client 144] Accepted from localhost:47476 (IPv6)
D [02/Jul/2017:10:13:24 +0100] [Client 144] Waiting for request.
I [02/Jul/2017:10:13:24 +0100] Expiring subscriptions...
D [02/Jul/2017:10:13:24 +0100] Report: clients=1
D [02/Jul/2017:10:13:24 +0100] Report: jobs=3
D [02/Jul/2017:10:13:24 +0100] Report: jobs-active=0
D [02/Jul/2017:10:13:24 +0100] Report: printers=1
D [02/Jul/2017:10:13:24 +0100] Report: stringpool-string-count=2147
D [02/Jul/2017:10:13:24 +0100] Report: stringpool-alloc-bytes=5888
D [02/Jul/2017:10:13:24 +0100] Report: stringpool-total-bytes=42360
D [02/Jul/2017:10:13:24 +0100] [Client 145] Accepted from localhost:47478 (IPv6)
D [02/Jul/2017:10:13:24 +0100] [Client 145] Waiting for request.
D [02/Jul/2017:10:13:24 +0100] [Client 144] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:13:24 +0100] [Client 144] Closing connection.
D [02/Jul/2017:10:13:24 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [02/Jul/2017:10:13:24 +0100] [Client 146] Accepted from localhost:56450 (IPv4)
D [02/Jul/2017:10:13:24 +0100] [Client 146] Waiting for request.
D [02/Jul/2017:10:13:24 +0100] [Client 145] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:13:24 +0100] [Client 145] Closing connection.
D [02/Jul/2017:10:13:24 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [02/Jul/2017:10:13:24 +0100] [Client 146] POST / HTTP/1.1
D [02/Jul/2017:10:13:24 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [02/Jul/2017:10:13:24 +0100] [Client 146] Read: status=200
D [02/Jul/2017:10:13:24 +0100] [Client 146] No authentication data provided.
D [02/Jul/2017:10:13:24 +0100] [Client 146] 1.1 CUPS-Get-Default 1
D [02/Jul/2017:10:13:24 +0100] CUPS-Get-Default
D [02/Jul/2017:10:13:24 +0100] CUPS-Get-Default client-error-not-found: No default printer.
D [02/Jul/2017:10:13:24 +0100] [Client 146] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [02/Jul/2017:10:13:24 +0100] [Client 146] Content-Length: 110
D [02/Jul/2017:10:13:24 +0100] [Client 146] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [02/Jul/2017:10:13:24 +0100] [Client 146] con->http=0x55b93b9223d0
D [02/Jul/2017:10:13:24 +0100] [Client 146] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=110, response=0x55b93b9555b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [02/Jul/2017:10:13:24 +0100] [Client 146] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [02/Jul/2017:10:13:24 +0100] [Client 146] bytes=0, http_state=0, data_remaining=110
D [02/Jul/2017:10:13:24 +0100] [Client 146] Flushing write buffer.
D [02/Jul/2017:10:13:24 +0100] [Client 146] New state is HTTP_STATE_WAITING
D [02/Jul/2017:10:13:24 +0100] [Client 146] Waiting for request.
D [02/Jul/2017:10:13:24 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [02/Jul/2017:10:13:24 +0100] [Client 147] Accepted from localhost:47482 (IPv6)
D [02/Jul/2017:10:13:24 +0100] [Client 147] Waiting for request.
D [02/Jul/2017:10:13:24 +0100] [Client 147] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:13:24 +0100] [Client 147] Closing connection.
D [02/Jul/2017:10:13:24 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [02/Jul/2017:10:13:24 +0100] [Client 146] POST / HTTP/1.1
D [02/Jul/2017:10:13:24 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [02/Jul/2017:10:13:24 +0100] [Client 146] Read: status=200
D [02/Jul/2017:10:13:24 +0100] [Client 146] No authentication data provided.
D [02/Jul/2017:10:13:24 +0100] [Client 146] 1.1 CUPS-Get-Printers 1
D [02/Jul/2017:10:13:24 +0100] CUPS-Get-Printers
D [02/Jul/2017:10:13:24 +0100] [Client 146] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [02/Jul/2017:10:13:24 +0100] [Client 146] Content-Length: 141
D [02/Jul/2017:10:13:24 +0100] [Client 146] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [02/Jul/2017:10:13:24 +0100] [Client 146] con->http=0x55b93b9223d0
D [02/Jul/2017:10:13:24 +0100] [Client 146] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=141, response=0x55b93b9558e0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [02/Jul/2017:10:13:24 +0100] [Client 146] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [02/Jul/2017:10:13:24 +0100] [Client 146] bytes=0, http_state=0, data_remaining=141
D [02/Jul/2017:10:13:24 +0100] [Client 146] Flushing write buffer.
D [02/Jul/2017:10:13:24 +0100] [Client 146] New state is HTTP_STATE_WAITING
D [02/Jul/2017:10:13:24 +0100] [Client 146] Waiting for request.
D [02/Jul/2017:10:13:24 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
I [02/Jul/2017:10:13:25 +0100] Expiring subscriptions...
D [02/Jul/2017:10:13:39 +0100] [Client 146] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:13:39 +0100] [Client 146] Closing connection.
D [02/Jul/2017:10:13:39 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [02/Jul/2017:10:13:39 +0100] Expiring subscriptions...
I [02/Jul/2017:10:13:40 +0100] Expiring subscriptions...
D [02/Jul/2017:10:15:24 +0100] [Client 148] Accepted from localhost:47484 (IPv6)
D [02/Jul/2017:10:15:24 +0100] [Client 148] Waiting for request.
I [02/Jul/2017:10:15:24 +0100] Expiring subscriptions...
D [02/Jul/2017:10:15:24 +0100] Report: clients=1
D [02/Jul/2017:10:15:24 +0100] Report: jobs=3
D [02/Jul/2017:10:15:24 +0100] Report: jobs-active=0
D [02/Jul/2017:10:15:24 +0100] Report: printers=1
D [02/Jul/2017:10:15:24 +0100] Report: stringpool-string-count=2147
D [02/Jul/2017:10:15:24 +0100] Report: stringpool-alloc-bytes=5888
D [02/Jul/2017:10:15:24 +0100] Report: stringpool-total-bytes=42360
D [02/Jul/2017:10:15:24 +0100] [Client 149] Accepted from localhost:47486 (IPv6)
D [02/Jul/2017:10:15:24 +0100] [Client 149] Waiting for request.
D [02/Jul/2017:10:15:24 +0100] [Client 148] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:15:24 +0100] [Client 148] Closing connection.
D [02/Jul/2017:10:15:24 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [02/Jul/2017:10:15:24 +0100] [Client 149] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:15:24 +0100] [Client 149] Closing connection.
D [02/Jul/2017:10:15:24 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [02/Jul/2017:10:15:24 +0100] [Client 150] Accepted from localhost:56458 (IPv4)
D [02/Jul/2017:10:15:24 +0100] [Client 150] Waiting for request.
D [02/Jul/2017:10:15:24 +0100] [Client 150] POST / HTTP/1.1
D [02/Jul/2017:10:15:24 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [02/Jul/2017:10:15:24 +0100] [Client 150] Read: status=200
D [02/Jul/2017:10:15:24 +0100] [Client 150] No authentication data provided.
D [02/Jul/2017:10:15:24 +0100] [Client 150] 1.1 CUPS-Get-Default 1
D [02/Jul/2017:10:15:24 +0100] CUPS-Get-Default
D [02/Jul/2017:10:15:24 +0100] CUPS-Get-Default client-error-not-found: No default printer.
D [02/Jul/2017:10:15:24 +0100] [Client 150] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [02/Jul/2017:10:15:24 +0100] [Client 150] Content-Length: 110
D [02/Jul/2017:10:15:24 +0100] [Client 150] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [02/Jul/2017:10:15:24 +0100] [Client 150] con->http=0x55b93b9223d0
D [02/Jul/2017:10:15:24 +0100] [Client 150] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=110, response=0x55b93b9555b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [02/Jul/2017:10:15:24 +0100] [Client 150] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [02/Jul/2017:10:15:24 +0100] [Client 150] bytes=0, http_state=0, data_remaining=110
D [02/Jul/2017:10:15:24 +0100] [Client 150] Flushing write buffer.
D [02/Jul/2017:10:15:24 +0100] [Client 150] New state is HTTP_STATE_WAITING
D [02/Jul/2017:10:15:24 +0100] [Client 150] Waiting for request.
D [02/Jul/2017:10:15:24 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [02/Jul/2017:10:15:24 +0100] [Client 151] Accepted from localhost:47490 (IPv6)
D [02/Jul/2017:10:15:24 +0100] [Client 151] Waiting for request.
D [02/Jul/2017:10:15:24 +0100] [Client 151] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:15:24 +0100] [Client 151] Closing connection.
D [02/Jul/2017:10:15:24 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [02/Jul/2017:10:15:24 +0100] [Client 150] POST / HTTP/1.1
D [02/Jul/2017:10:15:24 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [02/Jul/2017:10:15:24 +0100] [Client 150] Read: status=200
D [02/Jul/2017:10:15:24 +0100] [Client 150] No authentication data provided.
D [02/Jul/2017:10:15:24 +0100] [Client 150] 1.1 CUPS-Get-Printers 1
D [02/Jul/2017:10:15:24 +0100] CUPS-Get-Printers
D [02/Jul/2017:10:15:24 +0100] [Client 150] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [02/Jul/2017:10:15:24 +0100] [Client 150] Content-Length: 141
D [02/Jul/2017:10:15:24 +0100] [Client 150] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [02/Jul/2017:10:15:24 +0100] [Client 150] con->http=0x55b93b9223d0
D [02/Jul/2017:10:15:24 +0100] [Client 150] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=141, response=0x55b93b9558e0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [02/Jul/2017:10:15:24 +0100] [Client 150] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [02/Jul/2017:10:15:24 +0100] [Client 150] bytes=0, http_state=0, data_remaining=141
D [02/Jul/2017:10:15:24 +0100] [Client 150] Flushing write buffer.
D [02/Jul/2017:10:15:24 +0100] [Client 150] New state is HTTP_STATE_WAITING
D [02/Jul/2017:10:15:24 +0100] [Client 150] Waiting for request.
D [02/Jul/2017:10:15:24 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
I [02/Jul/2017:10:15:25 +0100] Expiring subscriptions...
D [02/Jul/2017:10:15:39 +0100] [Client 150] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:15:39 +0100] [Client 150] Closing connection.
D [02/Jul/2017:10:15:39 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [02/Jul/2017:10:15:39 +0100] Expiring subscriptions...
I [02/Jul/2017:10:15:40 +0100] Expiring subscriptions...
D [02/Jul/2017:10:17:24 +0100] [Client 152] Accepted from localhost:47492 (IPv6)
D [02/Jul/2017:10:17:24 +0100] [Client 152] Waiting for request.
I [02/Jul/2017:10:17:24 +0100] Expiring subscriptions...
D [02/Jul/2017:10:17:24 +0100] cupsdAddCert: Adding certificate for PID 0
D [02/Jul/2017:10:17:24 +0100] Report: clients=1
D [02/Jul/2017:10:17:24 +0100] Report: jobs=3
D [02/Jul/2017:10:17:24 +0100] Report: jobs-active=0
D [02/Jul/2017:10:17:24 +0100] Report: printers=1
D [02/Jul/2017:10:17:24 +0100] Report: stringpool-string-count=2147
D [02/Jul/2017:10:17:24 +0100] Report: stringpool-alloc-bytes=5888
D [02/Jul/2017:10:17:24 +0100] Report: stringpool-total-bytes=42360
D [02/Jul/2017:10:17:24 +0100] [Client 153] Accepted from localhost:47494 (IPv6)
D [02/Jul/2017:10:17:24 +0100] [Client 153] Waiting for request.
D [02/Jul/2017:10:17:24 +0100] [Client 152] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:17:24 +0100] [Client 152] Closing connection.
D [02/Jul/2017:10:17:24 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [02/Jul/2017:10:17:24 +0100] [Client 153] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:17:24 +0100] [Client 153] Closing connection.
D [02/Jul/2017:10:17:24 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [02/Jul/2017:10:17:24 +0100] [Client 154] Accepted from localhost:56466 (IPv4)
D [02/Jul/2017:10:17:24 +0100] [Client 154] Waiting for request.
D [02/Jul/2017:10:17:24 +0100] [Client 154] POST / HTTP/1.1
D [02/Jul/2017:10:17:24 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [02/Jul/2017:10:17:24 +0100] [Client 154] Read: status=200
D [02/Jul/2017:10:17:24 +0100] [Client 154] No authentication data provided.
D [02/Jul/2017:10:17:24 +0100] [Client 154] 1.1 CUPS-Get-Default 1
D [02/Jul/2017:10:17:24 +0100] CUPS-Get-Default
D [02/Jul/2017:10:17:24 +0100] CUPS-Get-Default client-error-not-found: No default printer.
D [02/Jul/2017:10:17:24 +0100] [Client 154] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [02/Jul/2017:10:17:24 +0100] [Client 154] Content-Length: 110
D [02/Jul/2017:10:17:24 +0100] [Client 154] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [02/Jul/2017:10:17:24 +0100] [Client 154] con->http=0x55b93b9223d0
D [02/Jul/2017:10:17:24 +0100] [Client 154] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=110, response=0x55b93b9555b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [02/Jul/2017:10:17:24 +0100] [Client 154] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [02/Jul/2017:10:17:24 +0100] [Client 154] bytes=0, http_state=0, data_remaining=110
D [02/Jul/2017:10:17:24 +0100] [Client 154] Flushing write buffer.
D [02/Jul/2017:10:17:24 +0100] [Client 154] New state is HTTP_STATE_WAITING
D [02/Jul/2017:10:17:24 +0100] [Client 154] Waiting for request.
D [02/Jul/2017:10:17:24 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [02/Jul/2017:10:17:24 +0100] [Client 155] Accepted from localhost:47498 (IPv6)
D [02/Jul/2017:10:17:24 +0100] [Client 155] Waiting for request.
D [02/Jul/2017:10:17:24 +0100] [Client 155] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:17:24 +0100] [Client 155] Closing connection.
D [02/Jul/2017:10:17:24 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [02/Jul/2017:10:17:24 +0100] [Client 154] POST / HTTP/1.1
D [02/Jul/2017:10:17:24 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [02/Jul/2017:10:17:24 +0100] [Client 154] Read: status=200
D [02/Jul/2017:10:17:24 +0100] [Client 154] No authentication data provided.
D [02/Jul/2017:10:17:24 +0100] [Client 154] 1.1 CUPS-Get-Printers 1
D [02/Jul/2017:10:17:24 +0100] CUPS-Get-Printers
D [02/Jul/2017:10:17:24 +0100] [Client 154] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [02/Jul/2017:10:17:24 +0100] [Client 154] Content-Length: 141
D [02/Jul/2017:10:17:24 +0100] [Client 154] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [02/Jul/2017:10:17:24 +0100] [Client 154] con->http=0x55b93b9223d0
D [02/Jul/2017:10:17:24 +0100] [Client 154] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=141, response=0x55b93b9558e0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [02/Jul/2017:10:17:24 +0100] [Client 154] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [02/Jul/2017:10:17:24 +0100] [Client 154] bytes=0, http_state=0, data_remaining=141
D [02/Jul/2017:10:17:24 +0100] [Client 154] Flushing write buffer.
D [02/Jul/2017:10:17:24 +0100] [Client 154] New state is HTTP_STATE_WAITING
D [02/Jul/2017:10:17:24 +0100] [Client 154] Waiting for request.
D [02/Jul/2017:10:17:24 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
I [02/Jul/2017:10:17:25 +0100] Expiring subscriptions...
D [02/Jul/2017:10:17:39 +0100] [Client 154] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Jul/2017:10:17:39 +0100] [Client 154] Closing connection.
D [02/Jul/2017:10:17:39 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [02/Jul/2017:10:17:39 +0100] Expiring subscriptions...
I [02/Jul/2017:10:17:40 +0100] Expiring subscriptions...