E [16/Oct/2017:18:12:20 +0100] [cups-deviced] PID 15205 (gutenprint52+usb) stopped with status 1!
E [16/Oct/2017:18:22:17 +0100] [cups-deviced] PID 15412 (gutenprint52+usb) stopped with status 1!
I [16/Oct/2017:18:32:24 +0100] Listening to 0.0.0.0:631 (IPv4)
I [16/Oct/2017:18:32:24 +0100] Listening to [v1.::]:631 (IPv6)
I [16/Oct/2017:18:32:24 +0100] Remote access is enabled.
D [16/Oct/2017:18:32:24 +0100] Added auto ServerAlias Desktop
I [16/Oct/2017:18:32:24 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
D [16/Oct/2017:18:32:24 +0100] Using keychain "/etc/cups/ssl" for server name "Desktop".
I [16/Oct/2017:18:32:24 +0100] Configured for up to 100 clients.
I [16/Oct/2017:18:32:24 +0100] Allowing up to 100 client connections per host.
I [16/Oct/2017:18:32:24 +0100] Using policy "default" as the default.
D [16/Oct/2017:18:32:24 +0100] load_ppd: Loading /var/cache/cups/HP-Deskjet-2540-series.data...
D [16/Oct/2017:18:32:24 +0100] cupsdRegisterPrinter(p=0x55f61addd930(HP-Deskjet-2540-series))
D [16/Oct/2017:18:32:24 +0100] load_ppd: Loading /var/cache/cups/Samsung-SCX-472x.data...
D [16/Oct/2017:18:32:24 +0100] cupsdRegisterPrinter(p=0x55f61ade29f0(Samsung-SCX-472x))
D [16/Oct/2017:18:32:24 +0100] cupsdMarkDirty(--p--)
D [16/Oct/2017:18:32:24 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Not busy"
I [16/Oct/2017:18:32:24 +0100] Partial reload complete.
D [16/Oct/2017:18:32:24 +0100] Calling FindDeviceById(cups-HP-Deskjet-2540-series)
D [16/Oct/2017:18:32:24 +0100] Calling DeleteDevice(/org/freedesktop/ColorManager/devices/cups_HP_Deskjet_2540_series)
D [16/Oct/2017:18:32:24 +0100] Using profile ID "HP-Deskjet-2540-series-Gray..".
D [16/Oct/2017:18:32:24 +0100] Calling CreateProfile(HP-Deskjet-2540-series-Gray..,temp)
W [16/Oct/2017:18:32:24 +0100] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id \'HP-Deskjet-2540-series-Gray..\' already exists
D [16/Oct/2017:18:32:24 +0100] Using profile ID "HP-Deskjet-2540-series-RGB..".
D [16/Oct/2017:18:32:24 +0100] Calling CreateProfile(HP-Deskjet-2540-series-RGB..,temp)
W [16/Oct/2017:18:32:24 +0100] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id \'HP-Deskjet-2540-series-RGB..\' already exists
I [16/Oct/2017:18:32:24 +0100] Registering ICC color profiles for "HP-Deskjet-2540-series".
D [16/Oct/2017:18:32:24 +0100] Calling CreateDevice(cups-HP-Deskjet-2540-series,temp)
D [16/Oct/2017:18:32:24 +0100] Created device "/org/freedesktop/ColorManager/devices/cups_HP_Deskjet_2540_series".
D [16/Oct/2017:18:32:24 +0100] Calling FindDeviceById(cups-Samsung-SCX-472x)
D [16/Oct/2017:18:32:24 +0100] Calling DeleteDevice(/org/freedesktop/ColorManager/devices/cups_Samsung_SCX_472x)
D [16/Oct/2017:18:32:24 +0100] Using profile ID "Samsung-SCX-472x-Gray..".
D [16/Oct/2017:18:32:24 +0100] Calling CreateProfile(Samsung-SCX-472x-Gray..,temp)
W [16/Oct/2017:18:32:24 +0100] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id \'Samsung-SCX-472x-Gray..\' already exists
I [16/Oct/2017:18:32:24 +0100] Registering ICC color profiles for "Samsung-SCX-472x".
D [16/Oct/2017:18:32:24 +0100] Calling CreateDevice(cups-Samsung-SCX-472x,temp)
D [16/Oct/2017:18:32:24 +0100] Created device "/org/freedesktop/ColorManager/devices/cups_Samsung_SCX_472x".
I [16/Oct/2017:18:32:24 +0100] Listening to /var/run/cups/cups.sock on fd 3...
I [16/Oct/2017:18:32:24 +0100] Listening to 0.0.0.0:631 on fd 10...
I [16/Oct/2017:18:32:24 +0100] Listening to [v1.::]:631 on fd 11...
I [16/Oct/2017:18:32:24 +0100] Resuming new connection processing...
D [16/Oct/2017:18:32:24 +0100] Avahi server connection now available, registering printers for Bonjour broadcasting.
D [16/Oct/2017:18:32:24 +0100] Registering "HP Deskjet 2540 series @ Desktop" with DNS-SD type "_printer._tcp".
D [16/Oct/2017:18:32:24 +0100] Registering "HP Deskjet 2540 series @ Desktop" with DNS-SD type "_ipps._tcp".
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" printer-type=0x900E
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" printer-state=5
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" Color=T
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" TLS=1.2
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" UUID=84889fab-4faf-30ad-7f88-f04a2ff6edeb
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" URF=DM3
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster,image/urf
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" product=(HP Deskjet 2546r All-in-one Printer)
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" priority=0
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" note=Desktop
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" adminurl=https://Desktop.local:631/printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" ty=HP Deskjet 2540 Series, hpcups 3.17.9
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" rp=printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" qtotal=1
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" txtvers=1
D [16/Oct/2017:18:32:24 +0100] Registering "HP Deskjet 2540 series @ Desktop" with DNS-SD type "_ipp._tcp".
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" printer-type=0x900E
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" printer-state=5
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" Color=T
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" TLS=1.2
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" UUID=84889fab-4faf-30ad-7f88-f04a2ff6edeb
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" URF=DM3
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster,image/urf
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" product=(HP Deskjet 2546r All-in-one Printer)
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" priority=0
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" note=Desktop
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" adminurl=https://Desktop.local:631/printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" ty=HP Deskjet 2540 Series, hpcups 3.17.9
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" rp=printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" qtotal=1
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" txtvers=1
D [16/Oct/2017:18:32:24 +0100] Registering "HP Deskjet 2540 series @ Desktop" with DNS-SD type "_printer._tcp".
D [16/Oct/2017:18:32:24 +0100] Registering "HP Deskjet 2540 series @ Desktop" with DNS-SD type "_ipps._tcp".
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" printer-type=0x900E
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" printer-state=5
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" Color=T
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" TLS=1.2
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" UUID=84889fab-4faf-30ad-7f88-f04a2ff6edeb
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" URF=DM3
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster,image/urf
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" product=(HP Deskjet 2546r All-in-one Printer)
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" priority=0
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" note=Desktop
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" adminurl=https://Desktop.local:631/printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" ty=HP Deskjet 2540 Series, hpcups 3.17.9
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" rp=printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" qtotal=1
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" txtvers=1
D [16/Oct/2017:18:32:24 +0100] Registering "HP Deskjet 2540 series @ Desktop" with DNS-SD type "_ipp._tcp".
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" printer-type=0x900E
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" printer-state=5
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" Color=T
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" TLS=1.2
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" UUID=84889fab-4faf-30ad-7f88-f04a2ff6edeb
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" URF=DM3
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster,image/urf
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" product=(HP Deskjet 2546r All-in-one Printer)
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" priority=0
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" note=Desktop
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" adminurl=https://Desktop.local:631/printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" ty=HP Deskjet 2540 Series, hpcups 3.17.9
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" rp=printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" qtotal=1
D [16/Oct/2017:18:32:24 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" txtvers=1
D [16/Oct/2017:18:32:24 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [16/Oct/2017:18:32:24 +0100] Notifier dbus started - PID = 15957
D [16/Oct/2017:18:32:24 +0100] Notifier dbus started - PID = 15958
D [16/Oct/2017:18:32:24 +0100] Notifier dbus started - PID = 15959
D [16/Oct/2017:18:32:24 +0100] Notifier dbus started - PID = 15960
D [16/Oct/2017:18:32:24 +0100] cupsdMarkDirty(----S)
D [16/Oct/2017:18:32:24 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [16/Oct/2017:18:32:24 +0100] [Client 107] Accepted from localhost (Domain)
D [16/Oct/2017:18:32:24 +0100] [Client 107] Waiting for request.
D [16/Oct/2017:18:32:24 +0100] cupsdAddCert: Adding certificate for PID 0
D [16/Oct/2017:18:32:24 +0100] Report: clients=1
D [16/Oct/2017:18:32:24 +0100] Report: jobs=480
D [16/Oct/2017:18:32:24 +0100] Report: jobs-active=0
D [16/Oct/2017:18:32:24 +0100] Report: printers=2
D [16/Oct/2017:18:32:24 +0100] Report: stringpool-string-count=2744
D [16/Oct/2017:18:32:24 +0100] Report: stringpool-alloc-bytes=10936
D [16/Oct/2017:18:32:24 +0100] Report: stringpool-total-bytes=49456
D [16/Oct/2017:18:32:24 +0100] PID 7570 (/usr/lib/cups/notifier/dbus) exited with no errors.
D [16/Oct/2017:18:32:24 +0100] PID 15026 (/usr/lib/cups/notifier/dbus) was terminated normally with signal 15.
D [16/Oct/2017:18:32:24 +0100] PID 15027 (/usr/lib/cups/notifier/dbus) was terminated normally with signal 15.
D [16/Oct/2017:18:32:24 +0100] PID 15028 (/usr/lib/cups/notifier/dbus) was terminated normally with signal 15.
D [16/Oct/2017:18:32:24 +0100] [Notifier] state=3
D [16/Oct/2017:18:32:24 +0100] [Notifier] state=3
D [16/Oct/2017:18:32:24 +0100] [Notifier] Connected to D-BUS
D [16/Oct/2017:18:32:24 +0100] [Notifier] ServerRestarted
D [16/Oct/2017:18:32:24 +0100] [Notifier] Connected to D-BUS
D [16/Oct/2017:18:32:24 +0100] [Notifier] state=3
D [16/Oct/2017:18:32:24 +0100] [Notifier] Connected to D-BUS
D [16/Oct/2017:18:32:24 +0100] [Notifier] state=3
D [16/Oct/2017:18:32:24 +0100] [Notifier] Connected to D-BUS
D [16/Oct/2017:18:32:25 +0100] [Client 108] Accepted from localhost (Domain)
D [16/Oct/2017:18:32:25 +0100] [Client 108] Waiting for request.
I [16/Oct/2017:18:32:25 +0100] Expiring subscriptions...
I [16/Oct/2017:18:32:26 +0100] Expiring subscriptions...
D [16/Oct/2017:18:32:28 +0100] [Client 109] Accepted from localhost (Domain)
D [16/Oct/2017:18:32:28 +0100] [Client 109] Waiting for request.
I [16/Oct/2017:18:32:28 +0100] Expiring subscriptions...
D [16/Oct/2017:18:32:28 +0100] [Client 109] POST / HTTP/1.1
D [16/Oct/2017:18:32:28 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:32:28 +0100] [Client 109] Read: status=200
D [16/Oct/2017:18:32:28 +0100] [Client 109] No authentication data provided.
D [16/Oct/2017:18:32:28 +0100] [Client 109] 2.0 Get-Notifications 11
D [16/Oct/2017:18:32:28 +0100] Get-Notifications /
D [16/Oct/2017:18:32:28 +0100] cupsdIsAuthorized: requesting-user-name="john"
D [16/Oct/2017:18:32:28 +0100] [Client 109] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [16/Oct/2017:18:32:28 +0100] [Client 109] Content-Length: 127
D [16/Oct/2017:18:32:28 +0100] [Client 109] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:32:28 +0100] [Client 109] con->http=0x55f61aeeedc0
D [16/Oct/2017:18:32:28 +0100] [Client 109] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x55f61aecc040(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:32:28 +0100] [Client 109] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:32:28 +0100] [Client 109] bytes=0, http_state=0, data_remaining=127
D [16/Oct/2017:18:32:28 +0100] [Client 109] Flushing write buffer.
D [16/Oct/2017:18:32:28 +0100] [Client 109] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:32:28 +0100] [Client 109] Waiting for request.
D [16/Oct/2017:18:32:28 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:32:28 +0100] [Client 109] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:32:28 +0100] [Client 109] Closing connection.
D [16/Oct/2017:18:32:28 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [16/Oct/2017:18:32:29 +0100] Expiring subscriptions...
D [16/Oct/2017:18:32:54 +0100] [Client 107] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:32:54 +0100] [Client 107] Closing connection.
D [16/Oct/2017:18:32:54 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [16/Oct/2017:18:32:54 +0100] Generating printcap /var/run/cups/printcap...
I [16/Oct/2017:18:32:54 +0100] Saving subscriptions.conf...
D [16/Oct/2017:18:32:54 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
I [16/Oct/2017:18:32:54 +0100] Expiring subscriptions...
I [16/Oct/2017:18:32:55 +0100] Expiring subscriptions...
D [16/Oct/2017:18:33:03 +0100] [Client 110] Accepted from localhost (Domain)
D [16/Oct/2017:18:33:03 +0100] [Client 110] Waiting for request.
I [16/Oct/2017:18:33:03 +0100] Expiring subscriptions...
D [16/Oct/2017:18:33:03 +0100] [Client 110] PUT /admin/conf/cupsd.conf HTTP/1.1
D [16/Oct/2017:18:33:03 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:33:03 +0100] [Client 110] Read: status=200
D [16/Oct/2017:18:33:03 +0100] [Client 110] No authentication data provided.
D [16/Oct/2017:18:33:03 +0100] cupsdIsAuthorized: username=""
D [16/Oct/2017:18:33:03 +0100] [Client 110] cupsdSendHeader: code=401, type="text/html", auth_type=0
D [16/Oct/2017:18:33:03 +0100] [Client 110] WWW-Authenticate: Basic realm=\"CUPS\", trc=\"y\"
D [16/Oct/2017:18:33:03 +0100] [Client 110] Closing connection.
D [16/Oct/2017:18:33:03 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:33:03 +0100] [Client 111] Accepted from localhost (Domain)
D [16/Oct/2017:18:33:03 +0100] [Client 111] Waiting for request.
D [16/Oct/2017:18:33:03 +0100] [Client 112] Accepted from localhost (Domain)
D [16/Oct/2017:18:33:03 +0100] [Client 112] Waiting for request.
D [16/Oct/2017:18:33:03 +0100] [Client 111] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:33:03 +0100] [Client 111] Closing connection.
D [16/Oct/2017:18:33:03 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:33:03 +0100] [Client 113] Accepted from localhost (Domain)
D [16/Oct/2017:18:33:03 +0100] [Client 113] Waiting for request.
D [16/Oct/2017:18:33:03 +0100] [Client 112] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:33:03 +0100] [Client 112] Closing connection.
D [16/Oct/2017:18:33:03 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:33:03 +0100] [Client 113] PUT /admin/conf/cupsd.conf HTTP/1.1
D [16/Oct/2017:18:33:03 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:33:03 +0100] [Client 113] Read: status=200
D [16/Oct/2017:18:33:03 +0100] [Client 113] Authorized as john using PeerCred.
D [16/Oct/2017:18:33:03 +0100] cupsdIsAuthorized: username="john"
D [16/Oct/2017:18:33:03 +0100] [Client 113] Read: status=100
I [16/Oct/2017:18:33:03 +0100] [Client 113] Installing config file "/etc/cups/cupsd.conf"...
D [16/Oct/2017:18:33:03 +0100] [Client 113] cupsdSendHeader: code=201, type="(null)", auth_type=0
D [16/Oct/2017:18:33:03 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:33:03 +0100] [Client 108] Closing connection.
D [16/Oct/2017:18:33:03 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:33:03 +0100] [Client 113] Closing connection.
D [16/Oct/2017:18:33:03 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [16/Oct/2017:18:33:03 +0100] Listening to 0.0.0.0:631 (IPv4)
I [16/Oct/2017:18:33:03 +0100] Listening to [v1.::]:631 (IPv6)
I [16/Oct/2017:18:33:03 +0100] Remote access is enabled.
D [16/Oct/2017:18:33:03 +0100] Added auto ServerAlias Desktop
I [16/Oct/2017:18:33:03 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
D [16/Oct/2017:18:33:03 +0100] Using keychain "/etc/cups/ssl" for server name "Desktop".
I [16/Oct/2017:18:33:03 +0100] Configured for up to 100 clients.
I [16/Oct/2017:18:33:03 +0100] Allowing up to 100 client connections per host.
I [16/Oct/2017:18:33:03 +0100] Using policy "default" as the default.
D [16/Oct/2017:18:33:03 +0100] load_ppd: Loading /var/cache/cups/HP-Deskjet-2540-series.data...
D [16/Oct/2017:18:33:03 +0100] cupsdRegisterPrinter(p=0x55f61addd930(HP-Deskjet-2540-series))
D [16/Oct/2017:18:33:03 +0100] load_ppd: Loading /var/cache/cups/Samsung-SCX-472x.data...
D [16/Oct/2017:18:33:03 +0100] cupsdRegisterPrinter(p=0x55f61ade29f0(Samsung-SCX-472x))
D [16/Oct/2017:18:33:03 +0100] cupsdMarkDirty(--p--)
D [16/Oct/2017:18:33:03 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Not busy"
I [16/Oct/2017:18:33:03 +0100] Partial reload complete.
D [16/Oct/2017:18:33:03 +0100] Calling FindDeviceById(cups-HP-Deskjet-2540-series)
D [16/Oct/2017:18:33:03 +0100] Calling DeleteDevice(/org/freedesktop/ColorManager/devices/cups_HP_Deskjet_2540_series)
D [16/Oct/2017:18:33:03 +0100] Using profile ID "HP-Deskjet-2540-series-Gray..".
D [16/Oct/2017:18:33:03 +0100] Calling CreateProfile(HP-Deskjet-2540-series-Gray..,temp)
W [16/Oct/2017:18:33:03 +0100] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id \'HP-Deskjet-2540-series-Gray..\' already exists
D [16/Oct/2017:18:33:03 +0100] Using profile ID "HP-Deskjet-2540-series-RGB..".
D [16/Oct/2017:18:33:03 +0100] Calling CreateProfile(HP-Deskjet-2540-series-RGB..,temp)
W [16/Oct/2017:18:33:03 +0100] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id \'HP-Deskjet-2540-series-RGB..\' already exists
I [16/Oct/2017:18:33:03 +0100] Registering ICC color profiles for "HP-Deskjet-2540-series".
D [16/Oct/2017:18:33:03 +0100] Calling CreateDevice(cups-HP-Deskjet-2540-series,temp)
D [16/Oct/2017:18:33:03 +0100] Created device "/org/freedesktop/ColorManager/devices/cups_HP_Deskjet_2540_series".
D [16/Oct/2017:18:33:03 +0100] Calling FindDeviceById(cups-Samsung-SCX-472x)
D [16/Oct/2017:18:33:03 +0100] Calling DeleteDevice(/org/freedesktop/ColorManager/devices/cups_Samsung_SCX_472x)
D [16/Oct/2017:18:33:03 +0100] Using profile ID "Samsung-SCX-472x-Gray..".
D [16/Oct/2017:18:33:03 +0100] Calling CreateProfile(Samsung-SCX-472x-Gray..,temp)
W [16/Oct/2017:18:33:03 +0100] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id \'Samsung-SCX-472x-Gray..\' already exists
I [16/Oct/2017:18:33:03 +0100] Registering ICC color profiles for "Samsung-SCX-472x".
D [16/Oct/2017:18:33:03 +0100] Calling CreateDevice(cups-Samsung-SCX-472x,temp)
D [16/Oct/2017:18:33:03 +0100] Created device "/org/freedesktop/ColorManager/devices/cups_Samsung_SCX_472x".
I [16/Oct/2017:18:33:03 +0100] Listening to /var/run/cups/cups.sock on fd 3...
I [16/Oct/2017:18:33:03 +0100] Listening to 0.0.0.0:631 on fd 10...
I [16/Oct/2017:18:33:03 +0100] Listening to [v1.::]:631 on fd 11...
I [16/Oct/2017:18:33:03 +0100] Resuming new connection processing...
D [16/Oct/2017:18:33:03 +0100] Avahi server connection now available, registering printers for Bonjour broadcasting.
D [16/Oct/2017:18:33:03 +0100] Registering "HP Deskjet 2540 series @ Desktop" with DNS-SD type "_printer._tcp".
D [16/Oct/2017:18:33:03 +0100] Registering "HP Deskjet 2540 series @ Desktop" with DNS-SD type "_ipps._tcp".
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" printer-type=0x900E
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" printer-state=5
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" Color=T
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" TLS=1.2
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" UUID=84889fab-4faf-30ad-7f88-f04a2ff6edeb
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" URF=DM3
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster,image/urf
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" product=(HP Deskjet 2546r All-in-one Printer)
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" priority=0
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" note=Desktop
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" adminurl=https://Desktop.local:631/printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" ty=HP Deskjet 2540 Series, hpcups 3.17.9
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" rp=printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" qtotal=1
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" txtvers=1
D [16/Oct/2017:18:33:03 +0100] Registering "HP Deskjet 2540 series @ Desktop" with DNS-SD type "_ipp._tcp".
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" printer-type=0x900E
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" printer-state=5
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" Color=T
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" TLS=1.2
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" UUID=84889fab-4faf-30ad-7f88-f04a2ff6edeb
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" URF=DM3
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster,image/urf
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" product=(HP Deskjet 2546r All-in-one Printer)
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" priority=0
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" note=Desktop
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" adminurl=https://Desktop.local:631/printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" ty=HP Deskjet 2540 Series, hpcups 3.17.9
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" rp=printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" qtotal=1
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" txtvers=1
D [16/Oct/2017:18:33:03 +0100] Registering "HP Deskjet 2540 series @ Desktop" with DNS-SD type "_printer._tcp".
D [16/Oct/2017:18:33:03 +0100] Registering "HP Deskjet 2540 series @ Desktop" with DNS-SD type "_ipps._tcp".
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" printer-type=0x900E
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" printer-state=5
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" Color=T
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" TLS=1.2
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" UUID=84889fab-4faf-30ad-7f88-f04a2ff6edeb
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" URF=DM3
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster,image/urf
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" product=(HP Deskjet 2546r All-in-one Printer)
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" priority=0
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" note=Desktop
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" adminurl=https://Desktop.local:631/printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" ty=HP Deskjet 2540 Series, hpcups 3.17.9
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" rp=printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" qtotal=1
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" txtvers=1
D [16/Oct/2017:18:33:03 +0100] Registering "HP Deskjet 2540 series @ Desktop" with DNS-SD type "_ipp._tcp".
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" printer-type=0x900E
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" printer-state=5
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" Color=T
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" TLS=1.2
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" UUID=84889fab-4faf-30ad-7f88-f04a2ff6edeb
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" URF=DM3
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster,image/urf
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" product=(HP Deskjet 2546r All-in-one Printer)
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" priority=0
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" note=Desktop
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" adminurl=https://Desktop.local:631/printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" ty=HP Deskjet 2540 Series, hpcups 3.17.9
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" rp=printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" qtotal=1
D [16/Oct/2017:18:33:03 +0100] DNS_SD "HP Deskjet 2540 series @ Desktop" txtvers=1
D [16/Oct/2017:18:33:03 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:03 +0100] Notifier dbus started - PID = 16006
D [16/Oct/2017:18:33:03 +0100] Notifier dbus started - PID = 16007
D [16/Oct/2017:18:33:03 +0100] Notifier dbus started - PID = 16008
D [16/Oct/2017:18:33:03 +0100] Notifier dbus started - PID = 16009
D [16/Oct/2017:18:33:03 +0100] cupsdMarkDirty(----S)
D [16/Oct/2017:18:33:03 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:03 +0100] [Notifier] state=3
D [16/Oct/2017:18:33:03 +0100] PID 15957 (/usr/lib/cups/notifier/dbus) exited with no errors.
D [16/Oct/2017:18:33:03 +0100] PID 15958 (/usr/lib/cups/notifier/dbus) was terminated normally with signal 15.
D [16/Oct/2017:18:33:03 +0100] PID 15959 (/usr/lib/cups/notifier/dbus) was terminated normally with signal 15.
D [16/Oct/2017:18:33:03 +0100] PID 15960 (/usr/lib/cups/notifier/dbus) was terminated normally with signal 15.
D [16/Oct/2017:18:33:03 +0100] [Notifier] state=3
D [16/Oct/2017:18:33:03 +0100] [Notifier] Connected to D-BUS
D [16/Oct/2017:18:33:03 +0100] [Notifier] ServerRestarted
D [16/Oct/2017:18:33:03 +0100] [Notifier] Connected to D-BUS
D [16/Oct/2017:18:33:03 +0100] [Notifier] state=3
D [16/Oct/2017:18:33:03 +0100] [Notifier] Connected to D-BUS
D [16/Oct/2017:18:33:03 +0100] [Notifier] state=3
D [16/Oct/2017:18:33:03 +0100] [Notifier] Connected to D-BUS
I [16/Oct/2017:18:33:04 +0100] Expiring subscriptions...
D [16/Oct/2017:18:33:28 +0100] [Client 114] Accepted from localhost (Domain)
D [16/Oct/2017:18:33:28 +0100] [Client 114] Waiting for request.
I [16/Oct/2017:18:33:28 +0100] Expiring subscriptions...
D [16/Oct/2017:18:33:28 +0100] cupsdAddCert: Adding certificate for PID 0
D [16/Oct/2017:18:33:28 +0100] Report: clients=1
D [16/Oct/2017:18:33:28 +0100] Report: jobs=480
D [16/Oct/2017:18:33:28 +0100] Report: jobs-active=0
D [16/Oct/2017:18:33:28 +0100] Report: printers=2
D [16/Oct/2017:18:33:28 +0100] Report: stringpool-string-count=2915
D [16/Oct/2017:18:33:28 +0100] Report: stringpool-alloc-bytes=11144
D [16/Oct/2017:18:33:28 +0100] Report: stringpool-total-bytes=51488
D [16/Oct/2017:18:33:28 +0100] [Client 115] Accepted from localhost (Domain)
D [16/Oct/2017:18:33:28 +0100] [Client 115] Waiting for request.
D [16/Oct/2017:18:33:28 +0100] [Client 115] POST / HTTP/1.1
D [16/Oct/2017:18:33:28 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:28 +0100] [Client 115] Read: status=200
D [16/Oct/2017:18:33:28 +0100] [Client 115] No authentication data provided.
D [16/Oct/2017:18:33:28 +0100] [Client 115] Read: status=100
D [16/Oct/2017:18:33:28 +0100] [Client 115] Read: status=100
D [16/Oct/2017:18:33:28 +0100] [Client 115] Read: status=100
D [16/Oct/2017:18:33:28 +0100] [Client 115] Read: status=100
D [16/Oct/2017:18:33:28 +0100] [Client 115] Read: status=100
D [16/Oct/2017:18:33:28 +0100] [Client 115] Read: status=100
D [16/Oct/2017:18:33:28 +0100] [Client 115] Read: status=100
D [16/Oct/2017:18:33:28 +0100] [Client 115] 2.0 Get-Notifications 12
D [16/Oct/2017:18:33:28 +0100] Get-Notifications /
D [16/Oct/2017:18:33:28 +0100] cupsdIsAuthorized: requesting-user-name="john"
D [16/Oct/2017:18:33:28 +0100] [Client 115] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [16/Oct/2017:18:33:28 +0100] [Client 115] Content-Length: 127
D [16/Oct/2017:18:33:28 +0100] [Client 115] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:28 +0100] [Client 115] con->http=0x55f61aeff730
D [16/Oct/2017:18:33:28 +0100] [Client 115] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x55f61addd680(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:28 +0100] [Client 115] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:28 +0100] [Client 115] bytes=0, http_state=0, data_remaining=127
D [16/Oct/2017:18:33:28 +0100] [Client 115] Flushing write buffer.
D [16/Oct/2017:18:33:28 +0100] [Client 115] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:28 +0100] [Client 115] Waiting for request.
D [16/Oct/2017:18:33:28 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:28 +0100] [Client 115] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:33:28 +0100] [Client 115] Closing connection.
D [16/Oct/2017:18:33:28 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:29 +0100] [Client 114] POST / HTTP/1.1
D [16/Oct/2017:18:33:29 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:29 +0100] [Client 114] Read: status=200
D [16/Oct/2017:18:33:29 +0100] [Client 114] No authentication data provided.
D [16/Oct/2017:18:33:29 +0100] [Client 114] 2.0 CUPS-Get-Printers 1
D [16/Oct/2017:18:33:29 +0100] CUPS-Get-Printers
D [16/Oct/2017:18:33:29 +0100] [Client 114] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [16/Oct/2017:18:33:29 +0100] [Client 114] Content-Length: 906
D [16/Oct/2017:18:33:29 +0100] [Client 114] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
I [16/Oct/2017:18:33:29 +0100] Expiring subscriptions...
D [16/Oct/2017:18:33:29 +0100] [Client 114] con->http=0x55f61aefb360
D [16/Oct/2017:18:33:29 +0100] [Client 114] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=906, response=0x55f61addd520(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:29 +0100] [Client 114] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:29 +0100] [Client 114] bytes=0, http_state=0, data_remaining=906
D [16/Oct/2017:18:33:29 +0100] [Client 114] Flushing write buffer.
D [16/Oct/2017:18:33:29 +0100] [Client 114] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:29 +0100] [Client 114] Waiting for request.
D [16/Oct/2017:18:33:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:29 +0100] [Client 114] POST / HTTP/1.1
D [16/Oct/2017:18:33:29 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:29 +0100] [Client 114] Read: status=200
D [16/Oct/2017:18:33:29 +0100] [Client 114] No authentication data provided.
D [16/Oct/2017:18:33:29 +0100] [Client 114] 2.0 CUPS-Get-Classes 2
D [16/Oct/2017:18:33:29 +0100] CUPS-Get-Classes
D [16/Oct/2017:18:33:29 +0100] [Client 114] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [16/Oct/2017:18:33:29 +0100] [Client 114] Content-Length: 75
D [16/Oct/2017:18:33:29 +0100] [Client 114] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:29 +0100] [Client 114] con->http=0x55f61aefb360
D [16/Oct/2017:18:33:29 +0100] [Client 114] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55f61aec88a0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:29 +0100] [Client 114] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:29 +0100] [Client 114] bytes=0, http_state=0, data_remaining=75
D [16/Oct/2017:18:33:29 +0100] [Client 114] Flushing write buffer.
D [16/Oct/2017:18:33:29 +0100] [Client 114] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:29 +0100] [Client 114] Waiting for request.
D [16/Oct/2017:18:33:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:29 +0100] [Client 114] POST / HTTP/1.1
D [16/Oct/2017:18:33:29 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:29 +0100] [Client 114] Read: status=200
D [16/Oct/2017:18:33:29 +0100] [Client 114] No authentication data provided.
D [16/Oct/2017:18:33:29 +0100] [Client 114] 2.0 CUPS-Get-Default 3
D [16/Oct/2017:18:33:29 +0100] CUPS-Get-Default
D [16/Oct/2017:18:33:29 +0100] [Client 114] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [16/Oct/2017:18:33:29 +0100] [Client 114] Content-Length: 10078
D [16/Oct/2017:18:33:29 +0100] [Client 114] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:29 +0100] [Client 114] con->http=0x55f61aefb360
D [16/Oct/2017:18:33:29 +0100] [Client 114] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=10078, response=0x55f61addd520(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:29 +0100] [Client 114] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:29 +0100] [Client 114] bytes=0, http_state=0, data_remaining=10078
D [16/Oct/2017:18:33:29 +0100] [Client 114] Flushing write buffer.
D [16/Oct/2017:18:33:29 +0100] [Client 114] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:29 +0100] [Client 114] Waiting for request.
D [16/Oct/2017:18:33:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:29 +0100] [Client 116] Accepted from localhost (Domain)
D [16/Oct/2017:18:33:29 +0100] [Client 116] Waiting for request.
D [16/Oct/2017:18:33:29 +0100] [Client 116] POST / HTTP/1.1
D [16/Oct/2017:18:33:29 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:29 +0100] [Client 116] Read: status=200
D [16/Oct/2017:18:33:29 +0100] [Client 116] No authentication data provided.
D [16/Oct/2017:18:33:29 +0100] [Client 116] 2.0 Create-Printer-Subscriptions 4
D [16/Oct/2017:18:33:29 +0100] Create-Printer-Subscriptions /
D [16/Oct/2017:18:33:29 +0100] create_subscriptions(con=0x55f61aefe570(116), uri="/")
D [16/Oct/2017:18:33:29 +0100] pullmethod="ippget"
D [16/Oct/2017:18:33:29 +0100] notify-lease-duration=86400
D [16/Oct/2017:18:33:29 +0100] notify-time-interval=0
D [16/Oct/2017:18:33:29 +0100] cupsdAddSubscription(mask=38f, dest=(nil)(), job=(nil)(0), uri="(null)")
D [16/Oct/2017:18:33:29 +0100] Added subscription #3174 for server.
D [16/Oct/2017:18:33:29 +0100] cupsdMarkDirty(----S)
D [16/Oct/2017:18:33:29 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:29 +0100] [Client 116] Returning IPP successful-ok for Create-Printer-Subscriptions (/) from localhost
D [16/Oct/2017:18:33:29 +0100] [Client 116] Content-Length: 107
D [16/Oct/2017:18:33:29 +0100] [Client 116] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:29 +0100] [Client 116] con->http=0x55f61aeff730
D [16/Oct/2017:18:33:29 +0100] [Client 116] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=107, response=0x55f61addd770(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:29 +0100] [Client 116] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:29 +0100] [Client 116] bytes=0, http_state=0, data_remaining=107
D [16/Oct/2017:18:33:29 +0100] [Client 116] Flushing write buffer.
D [16/Oct/2017:18:33:29 +0100] [Client 116] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:29 +0100] [Client 116] Waiting for request.
D [16/Oct/2017:18:33:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:29 +0100] [Client 116] POST / HTTP/1.1
D [16/Oct/2017:18:33:29 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:29 +0100] [Client 116] Read: status=200
D [16/Oct/2017:18:33:29 +0100] [Client 116] No authentication data provided.
D [16/Oct/2017:18:33:29 +0100] [Client 116] 2.0 CUPS-Get-Printers 5
D [16/Oct/2017:18:33:29 +0100] CUPS-Get-Printers
D [16/Oct/2017:18:33:29 +0100] [Client 116] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [16/Oct/2017:18:33:29 +0100] [Client 116] Content-Length: 906
D [16/Oct/2017:18:33:29 +0100] [Client 116] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:29 +0100] [Client 116] con->http=0x55f61aeff730
D [16/Oct/2017:18:33:29 +0100] [Client 116] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=906, response=0x55f61addd490(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:29 +0100] [Client 116] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:29 +0100] [Client 116] bytes=0, http_state=0, data_remaining=906
D [16/Oct/2017:18:33:29 +0100] [Client 116] Flushing write buffer.
D [16/Oct/2017:18:33:29 +0100] [Client 116] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:29 +0100] [Client 116] Waiting for request.
D [16/Oct/2017:18:33:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:29 +0100] [Client 116] POST / HTTP/1.1
D [16/Oct/2017:18:33:29 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:29 +0100] [Client 116] Read: status=200
D [16/Oct/2017:18:33:29 +0100] [Client 116] No authentication data provided.
D [16/Oct/2017:18:33:29 +0100] [Client 116] 2.0 CUPS-Get-Printers 6
D [16/Oct/2017:18:33:29 +0100] CUPS-Get-Printers
D [16/Oct/2017:18:33:29 +0100] [Client 116] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [16/Oct/2017:18:33:29 +0100] [Client 116] Content-Length: 906
D [16/Oct/2017:18:33:29 +0100] [Client 116] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:29 +0100] [Client 116] con->http=0x55f61aeff730
D [16/Oct/2017:18:33:29 +0100] [Client 116] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=906, response=0x55f61addd6f0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:29 +0100] [Client 116] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:29 +0100] [Client 116] bytes=0, http_state=0, data_remaining=906
D [16/Oct/2017:18:33:29 +0100] [Client 116] Flushing write buffer.
D [16/Oct/2017:18:33:29 +0100] [Client 116] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:29 +0100] [Client 116] Waiting for request.
D [16/Oct/2017:18:33:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:29 +0100] [Client 116] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:33:29 +0100] [Client 116] Closing connection.
D [16/Oct/2017:18:33:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:29 +0100] [Client 114] POST / HTTP/1.1
D [16/Oct/2017:18:33:29 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:29 +0100] [Client 114] Read: status=200
D [16/Oct/2017:18:33:29 +0100] [Client 114] No authentication data provided.
D [16/Oct/2017:18:33:29 +0100] [Client 114] 2.0 CUPS-Get-Printers 7
D [16/Oct/2017:18:33:29 +0100] CUPS-Get-Printers
D [16/Oct/2017:18:33:29 +0100] [Client 114] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [16/Oct/2017:18:33:29 +0100] [Client 114] Content-Length: 906
D [16/Oct/2017:18:33:29 +0100] [Client 114] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:29 +0100] [Client 114] con->http=0x55f61aefb360
D [16/Oct/2017:18:33:29 +0100] [Client 114] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=906, response=0x55f61addd120(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:29 +0100] [Client 114] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:29 +0100] [Client 114] bytes=0, http_state=0, data_remaining=906
D [16/Oct/2017:18:33:29 +0100] [Client 114] Flushing write buffer.
D [16/Oct/2017:18:33:29 +0100] [Client 114] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:29 +0100] [Client 114] Waiting for request.
D [16/Oct/2017:18:33:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:29 +0100] [Client 114] POST / HTTP/1.1
D [16/Oct/2017:18:33:29 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:29 +0100] [Client 114] Read: status=200
D [16/Oct/2017:18:33:29 +0100] [Client 114] No authentication data provided.
D [16/Oct/2017:18:33:29 +0100] [Client 114] 2.0 CUPS-Get-Classes 8
D [16/Oct/2017:18:33:29 +0100] CUPS-Get-Classes
D [16/Oct/2017:18:33:29 +0100] [Client 114] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [16/Oct/2017:18:33:29 +0100] [Client 114] Content-Length: 75
D [16/Oct/2017:18:33:29 +0100] [Client 114] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:29 +0100] [Client 114] con->http=0x55f61aefb360
D [16/Oct/2017:18:33:29 +0100] [Client 114] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55f61aec88a0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:29 +0100] [Client 114] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:29 +0100] [Client 114] bytes=0, http_state=0, data_remaining=75
D [16/Oct/2017:18:33:29 +0100] [Client 114] Flushing write buffer.
D [16/Oct/2017:18:33:29 +0100] [Client 114] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:29 +0100] [Client 114] Waiting for request.
D [16/Oct/2017:18:33:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:29 +0100] [Client 114] POST / HTTP/1.1
D [16/Oct/2017:18:33:29 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:29 +0100] [Client 114] Read: status=200
D [16/Oct/2017:18:33:29 +0100] [Client 114] No authentication data provided.
D [16/Oct/2017:18:33:29 +0100] [Client 114] 2.0 CUPS-Get-Default 9
D [16/Oct/2017:18:33:29 +0100] CUPS-Get-Default
D [16/Oct/2017:18:33:29 +0100] [Client 114] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [16/Oct/2017:18:33:29 +0100] [Client 114] Content-Length: 10078
D [16/Oct/2017:18:33:29 +0100] [Client 114] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:29 +0100] [Client 114] con->http=0x55f61aefb360
D [16/Oct/2017:18:33:29 +0100] [Client 114] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=10078, response=0x55f61addd120(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:29 +0100] [Client 114] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:29 +0100] [Client 114] bytes=0, http_state=0, data_remaining=10078
D [16/Oct/2017:18:33:29 +0100] [Client 114] Flushing write buffer.
D [16/Oct/2017:18:33:29 +0100] [Client 114] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:29 +0100] [Client 114] Waiting for request.
D [16/Oct/2017:18:33:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [16/Oct/2017:18:33:30 +0100] Expiring subscriptions...
D [16/Oct/2017:18:33:30 +0100] [Client 117] Accepted from localhost (Domain)
D [16/Oct/2017:18:33:30 +0100] [Client 117] Waiting for request.
D [16/Oct/2017:18:33:30 +0100] [Client 117] POST / HTTP/1.1
D [16/Oct/2017:18:33:30 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:30 +0100] [Client 117] Read: status=200
D [16/Oct/2017:18:33:30 +0100] [Client 117] No authentication data provided.
D [16/Oct/2017:18:33:30 +0100] [Client 117] 2.0 Get-Notifications 10
D [16/Oct/2017:18:33:30 +0100] Get-Notifications /
D [16/Oct/2017:18:33:30 +0100] cupsdIsAuthorized: requesting-user-name="john"
D [16/Oct/2017:18:33:30 +0100] [Client 117] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [16/Oct/2017:18:33:30 +0100] [Client 117] Content-Length: 127
D [16/Oct/2017:18:33:30 +0100] [Client 117] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:30 +0100] [Client 117] con->http=0x55f61aeff730
D [16/Oct/2017:18:33:30 +0100] [Client 117] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x55f61addd280(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:30 +0100] [Client 117] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:30 +0100] [Client 117] bytes=0, http_state=0, data_remaining=127
D [16/Oct/2017:18:33:30 +0100] [Client 117] Flushing write buffer.
D [16/Oct/2017:18:33:30 +0100] [Client 117] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:30 +0100] [Client 117] Waiting for request.
D [16/Oct/2017:18:33:30 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:30 +0100] [Client 117] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:33:30 +0100] [Client 117] Closing connection.
D [16/Oct/2017:18:33:30 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [16/Oct/2017:18:33:31 +0100] Expiring subscriptions...
D [16/Oct/2017:18:33:32 +0100] [Client 118] Accepted from localhost (Domain)
D [16/Oct/2017:18:33:32 +0100] [Client 118] Waiting for request.
I [16/Oct/2017:18:33:32 +0100] Expiring subscriptions...
D [16/Oct/2017:18:33:32 +0100] [Client 118] POST / HTTP/1.1
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 118] Read: status=200
D [16/Oct/2017:18:33:32 +0100] [Client 118] No authentication data provided.
D [16/Oct/2017:18:33:32 +0100] [Client 118] 2.0 Create-Printer-Subscriptions 11
D [16/Oct/2017:18:33:32 +0100] Create-Printer-Subscriptions /
D [16/Oct/2017:18:33:32 +0100] create_subscriptions(con=0x55f61aefe570(118), uri="/")
D [16/Oct/2017:18:33:32 +0100] pullmethod="ippget"
D [16/Oct/2017:18:33:32 +0100] notify-lease-duration=86400
D [16/Oct/2017:18:33:32 +0100] notify-time-interval=0
D [16/Oct/2017:18:33:32 +0100] cupsdAddSubscription(mask=17b8f, dest=(nil)(), job=(nil)(0), uri="(null)")
D [16/Oct/2017:18:33:32 +0100] Added subscription #3175 for server.
D [16/Oct/2017:18:33:32 +0100] cupsdMarkDirty(----S)
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 118] Returning IPP successful-ok for Create-Printer-Subscriptions (/) from localhost
D [16/Oct/2017:18:33:32 +0100] [Client 118] Content-Length: 107
D [16/Oct/2017:18:33:32 +0100] [Client 118] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:32 +0100] [Client 118] con->http=0x55f61aeff730
D [16/Oct/2017:18:33:32 +0100] [Client 118] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=107, response=0x55f61addd2c0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:32 +0100] [Client 118] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:32 +0100] [Client 118] bytes=0, http_state=0, data_remaining=107
D [16/Oct/2017:18:33:32 +0100] [Client 118] Flushing write buffer.
D [16/Oct/2017:18:33:32 +0100] [Client 118] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:32 +0100] [Client 118] Waiting for request.
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 118] POST / HTTP/1.1
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 118] Read: status=200
D [16/Oct/2017:18:33:32 +0100] [Client 118] No authentication data provided.
D [16/Oct/2017:18:33:32 +0100] [Client 118] 2.0 CUPS-Get-Printers 12
D [16/Oct/2017:18:33:32 +0100] CUPS-Get-Printers
D [16/Oct/2017:18:33:32 +0100] [Client 118] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [16/Oct/2017:18:33:32 +0100] [Client 118] Content-Length: 906
D [16/Oct/2017:18:33:32 +0100] [Client 118] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:32 +0100] [Client 118] con->http=0x55f61aeff730
D [16/Oct/2017:18:33:32 +0100] [Client 118] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=906, response=0x55f61addd0c0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:32 +0100] [Client 118] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:32 +0100] [Client 118] bytes=0, http_state=0, data_remaining=906
D [16/Oct/2017:18:33:32 +0100] [Client 118] Flushing write buffer.
D [16/Oct/2017:18:33:32 +0100] [Client 118] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:32 +0100] [Client 118] Waiting for request.
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 118] POST / HTTP/1.1
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 118] Read: status=200
D [16/Oct/2017:18:33:32 +0100] [Client 118] No authentication data provided.
D [16/Oct/2017:18:33:32 +0100] [Client 118] 2.0 CUPS-Get-Printers 13
D [16/Oct/2017:18:33:32 +0100] CUPS-Get-Printers
D [16/Oct/2017:18:33:32 +0100] [Client 118] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [16/Oct/2017:18:33:32 +0100] [Client 118] Content-Length: 906
D [16/Oct/2017:18:33:32 +0100] [Client 118] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:32 +0100] [Client 118] con->http=0x55f61aeff730
D [16/Oct/2017:18:33:32 +0100] [Client 118] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=906, response=0x55f61addd2c0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:32 +0100] [Client 118] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:32 +0100] [Client 118] bytes=0, http_state=0, data_remaining=906
D [16/Oct/2017:18:33:32 +0100] [Client 118] Flushing write buffer.
D [16/Oct/2017:18:33:32 +0100] [Client 118] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:32 +0100] [Client 118] Waiting for request.
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 118] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:33:32 +0100] [Client 118] Closing connection.
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 119] Accepted from localhost (Domain)
D [16/Oct/2017:18:33:32 +0100] [Client 119] Waiting for request.
D [16/Oct/2017:18:33:32 +0100] [Client 119] POST / HTTP/1.1
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 119] Read: status=200
D [16/Oct/2017:18:33:32 +0100] [Client 119] No authentication data provided.
D [16/Oct/2017:18:33:32 +0100] [Client 119] 2.0 Create-Printer-Subscriptions 14
D [16/Oct/2017:18:33:32 +0100] Create-Printer-Subscriptions /
D [16/Oct/2017:18:33:32 +0100] create_subscriptions(con=0x55f61aefe570(119), uri="/")
D [16/Oct/2017:18:33:32 +0100] pullmethod="ippget"
D [16/Oct/2017:18:33:32 +0100] notify-lease-duration=86400
D [16/Oct/2017:18:33:32 +0100] notify-time-interval=0
D [16/Oct/2017:18:33:32 +0100] cupsdAddSubscription(mask=17b8f, dest=(nil)(), job=(nil)(0), uri="(null)")
D [16/Oct/2017:18:33:32 +0100] Added subscription #3176 for server.
D [16/Oct/2017:18:33:32 +0100] cupsdMarkDirty(----S)
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 119] Returning IPP successful-ok for Create-Printer-Subscriptions (/) from localhost
D [16/Oct/2017:18:33:32 +0100] [Client 119] Content-Length: 107
D [16/Oct/2017:18:33:32 +0100] [Client 119] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:32 +0100] [Client 119] con->http=0x55f61aeff730
D [16/Oct/2017:18:33:32 +0100] [Client 119] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=107, response=0x55f61addd860(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:32 +0100] [Client 119] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:32 +0100] [Client 119] bytes=0, http_state=0, data_remaining=107
D [16/Oct/2017:18:33:32 +0100] [Client 119] Flushing write buffer.
D [16/Oct/2017:18:33:32 +0100] [Client 119] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:32 +0100] [Client 119] Waiting for request.
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 119] POST / HTTP/1.1
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 119] Read: status=200
D [16/Oct/2017:18:33:32 +0100] [Client 119] No authentication data provided.
D [16/Oct/2017:18:33:32 +0100] [Client 119] 2.0 CUPS-Get-Printers 15
D [16/Oct/2017:18:33:32 +0100] CUPS-Get-Printers
D [16/Oct/2017:18:33:32 +0100] [Client 119] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [16/Oct/2017:18:33:32 +0100] [Client 119] Content-Length: 906
D [16/Oct/2017:18:33:32 +0100] [Client 119] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:32 +0100] [Client 119] con->http=0x55f61aeff730
D [16/Oct/2017:18:33:32 +0100] [Client 119] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=906, response=0x55f61addd490(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:32 +0100] [Client 119] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:32 +0100] [Client 119] bytes=0, http_state=0, data_remaining=906
D [16/Oct/2017:18:33:32 +0100] [Client 119] Flushing write buffer.
D [16/Oct/2017:18:33:32 +0100] [Client 119] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:32 +0100] [Client 119] Waiting for request.
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 119] POST / HTTP/1.1
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 119] Read: status=200
D [16/Oct/2017:18:33:32 +0100] [Client 119] No authentication data provided.
D [16/Oct/2017:18:33:32 +0100] [Client 119] 2.0 CUPS-Get-Printers 16
D [16/Oct/2017:18:33:32 +0100] CUPS-Get-Printers
D [16/Oct/2017:18:33:32 +0100] [Client 119] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [16/Oct/2017:18:33:32 +0100] [Client 119] Content-Length: 906
D [16/Oct/2017:18:33:32 +0100] [Client 119] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:32 +0100] [Client 119] con->http=0x55f61aeff730
D [16/Oct/2017:18:33:32 +0100] [Client 119] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=906, response=0x55f61addd860(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:32 +0100] [Client 119] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:32 +0100] [Client 119] bytes=0, http_state=0, data_remaining=906
D [16/Oct/2017:18:33:32 +0100] [Client 119] Flushing write buffer.
D [16/Oct/2017:18:33:32 +0100] [Client 119] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:32 +0100] [Client 119] Waiting for request.
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 119] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:33:32 +0100] [Client 119] Closing connection.
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 120] Accepted from localhost (Domain)
D [16/Oct/2017:18:33:32 +0100] [Client 120] Waiting for request.
D [16/Oct/2017:18:33:32 +0100] [Client 120] POST / HTTP/1.1
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 120] Read: status=200
D [16/Oct/2017:18:33:32 +0100] [Client 120] No authentication data provided.
D [16/Oct/2017:18:33:32 +0100] [Client 120] 2.0 Get-Jobs 17
D [16/Oct/2017:18:33:32 +0100] Get-Jobs ipp://localhost/printers/
D [16/Oct/2017:18:33:32 +0100] [Client 120] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [16/Oct/2017:18:33:32 +0100] [Client 120] Content-Length: 75
D [16/Oct/2017:18:33:32 +0100] [Client 120] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:32 +0100] [Client 120] con->http=0x55f61aeff730
D [16/Oct/2017:18:33:32 +0100] [Client 120] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55f61addd860(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:32 +0100] [Client 120] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:32 +0100] [Client 120] bytes=0, http_state=0, data_remaining=75
D [16/Oct/2017:18:33:32 +0100] [Client 120] Flushing write buffer.
D [16/Oct/2017:18:33:32 +0100] [Client 120] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:32 +0100] [Client 120] Waiting for request.
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 120] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:33:32 +0100] [Client 120] Closing connection.
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 121] Accepted from localhost (Domain)
D [16/Oct/2017:18:33:32 +0100] [Client 121] Waiting for request.
D [16/Oct/2017:18:33:32 +0100] [Client 121] POST / HTTP/1.1
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 121] Read: status=200
D [16/Oct/2017:18:33:32 +0100] [Client 121] No authentication data provided.
D [16/Oct/2017:18:33:32 +0100] [Client 121] 2.0 Get-Jobs 18
D [16/Oct/2017:18:33:32 +0100] Get-Jobs ipp://localhost/printers/
D [16/Oct/2017:18:33:32 +0100] [Client 121] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [16/Oct/2017:18:33:32 +0100] [Client 121] Content-Length: 75
D [16/Oct/2017:18:33:32 +0100] [Client 121] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:32 +0100] [Client 121] con->http=0x55f61aeff730
D [16/Oct/2017:18:33:32 +0100] [Client 121] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55f61addd710(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:32 +0100] [Client 121] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:32 +0100] [Client 121] bytes=0, http_state=0, data_remaining=75
D [16/Oct/2017:18:33:32 +0100] [Client 121] Flushing write buffer.
D [16/Oct/2017:18:33:32 +0100] [Client 121] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:32 +0100] [Client 121] Waiting for request.
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 121] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:33:32 +0100] [Client 121] Closing connection.
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 122] Accepted from localhost (Domain)
D [16/Oct/2017:18:33:32 +0100] [Client 122] Waiting for request.
D [16/Oct/2017:18:33:32 +0100] [Client 122] POST / HTTP/1.1
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 122] Read: status=200
D [16/Oct/2017:18:33:32 +0100] [Client 122] No authentication data provided.
D [16/Oct/2017:18:33:32 +0100] [Client 122] 2.0 Get-Printer-Attributes 19
D [16/Oct/2017:18:33:32 +0100] Get-Printer-Attributes ipp://localhost/printers/Samsung-SCX-472x
D [16/Oct/2017:18:33:32 +0100] [Client 122] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Samsung-SCX-472x) from localhost
D [16/Oct/2017:18:33:32 +0100] [Client 122] Content-Length: 240
D [16/Oct/2017:18:33:32 +0100] [Client 122] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:32 +0100] [Client 122] con->http=0x55f61aeff730
D [16/Oct/2017:18:33:32 +0100] [Client 122] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=240, response=0x55f61addd350(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:32 +0100] [Client 122] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:32 +0100] [Client 122] bytes=0, http_state=0, data_remaining=240
D [16/Oct/2017:18:33:32 +0100] [Client 122] Flushing write buffer.
D [16/Oct/2017:18:33:32 +0100] [Client 122] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:32 +0100] [Client 122] Waiting for request.
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 122] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:33:32 +0100] [Client 122] Closing connection.
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 123] Accepted from localhost (Domain)
D [16/Oct/2017:18:33:32 +0100] [Client 123] Waiting for request.
D [16/Oct/2017:18:33:32 +0100] [Client 123] POST / HTTP/1.1
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 123] Read: status=200
D [16/Oct/2017:18:33:32 +0100] [Client 123] No authentication data provided.
D [16/Oct/2017:18:33:32 +0100] [Client 123] 2.0 Get-Printer-Attributes 20
D [16/Oct/2017:18:33:32 +0100] Get-Printer-Attributes ipp://localhost/printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:33:32 +0100] [Client 123] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP-Deskjet-2540-series) from localhost
D [16/Oct/2017:18:33:32 +0100] [Client 123] Content-Length: 258
D [16/Oct/2017:18:33:32 +0100] [Client 123] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:32 +0100] [Client 123] con->http=0x55f61aeff730
D [16/Oct/2017:18:33:32 +0100] [Client 123] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=258, response=0x55f61addd170(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:32 +0100] [Client 123] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:32 +0100] [Client 123] bytes=0, http_state=0, data_remaining=258
D [16/Oct/2017:18:33:32 +0100] [Client 123] Flushing write buffer.
D [16/Oct/2017:18:33:32 +0100] [Client 123] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:32 +0100] [Client 123] Waiting for request.
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:33:32 +0100] [Client 123] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:33:32 +0100] [Client 123] Closing connection.
D [16/Oct/2017:18:33:32 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [16/Oct/2017:18:33:33 +0100] Generating printcap /var/run/cups/printcap...
I [16/Oct/2017:18:33:33 +0100] Saving subscriptions.conf...
D [16/Oct/2017:18:33:33 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
I [16/Oct/2017:18:33:33 +0100] Expiring subscriptions...
D [16/Oct/2017:18:33:33 +0100] [Client 124] Accepted from localhost (Domain)
D [16/Oct/2017:18:33:33 +0100] [Client 124] Waiting for request.
D [16/Oct/2017:18:33:33 +0100] [Client 124] POST / HTTP/1.1
D [16/Oct/2017:18:33:33 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:33:33 +0100] [Client 124] Read: status=200
D [16/Oct/2017:18:33:33 +0100] [Client 124] No authentication data provided.
D [16/Oct/2017:18:33:33 +0100] [Client 124] 2.0 Get-Notifications 21
D [16/Oct/2017:18:33:33 +0100] Get-Notifications /
D [16/Oct/2017:18:33:33 +0100] cupsdIsAuthorized: requesting-user-name="john"
D [16/Oct/2017:18:33:33 +0100] [Client 124] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [16/Oct/2017:18:33:33 +0100] [Client 124] Content-Length: 127
D [16/Oct/2017:18:33:33 +0100] [Client 124] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:33 +0100] [Client 124] con->http=0x55f61aeff730
D [16/Oct/2017:18:33:33 +0100] [Client 124] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x55f61addd350(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:33 +0100] [Client 124] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:33 +0100] [Client 124] bytes=0, http_state=0, data_remaining=127
D [16/Oct/2017:18:33:33 +0100] [Client 124] Flushing write buffer.
D [16/Oct/2017:18:33:33 +0100] [Client 124] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:33 +0100] [Client 124] Waiting for request.
D [16/Oct/2017:18:33:33 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:33:33 +0100] [Client 124] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:33:33 +0100] [Client 124] Closing connection.
D [16/Oct/2017:18:33:33 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:33:33 +0100] [Client 125] Accepted from localhost (Domain)
D [16/Oct/2017:18:33:33 +0100] [Client 125] Waiting for request.
D [16/Oct/2017:18:33:33 +0100] [Client 125] POST / HTTP/1.1
D [16/Oct/2017:18:33:33 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:33:33 +0100] [Client 125] Read: status=200
D [16/Oct/2017:18:33:33 +0100] [Client 125] No authentication data provided.
D [16/Oct/2017:18:33:33 +0100] [Client 125] 2.0 Get-Notifications 22
D [16/Oct/2017:18:33:33 +0100] Get-Notifications /
D [16/Oct/2017:18:33:33 +0100] cupsdIsAuthorized: requesting-user-name="john"
D [16/Oct/2017:18:33:33 +0100] [Client 125] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [16/Oct/2017:18:33:33 +0100] [Client 125] Content-Length: 127
D [16/Oct/2017:18:33:33 +0100] [Client 125] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:33:33 +0100] [Client 125] con->http=0x55f61aeff730
D [16/Oct/2017:18:33:33 +0100] [Client 125] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x55f61addd170(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:33:33 +0100] [Client 125] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:33:33 +0100] [Client 125] bytes=0, http_state=0, data_remaining=127
D [16/Oct/2017:18:33:33 +0100] [Client 125] Flushing write buffer.
D [16/Oct/2017:18:33:33 +0100] [Client 125] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:33:33 +0100] [Client 125] Waiting for request.
D [16/Oct/2017:18:33:33 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:33:33 +0100] [Client 125] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:33:33 +0100] [Client 125] Closing connection.
D [16/Oct/2017:18:33:33 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [16/Oct/2017:18:33:34 +0100] Expiring subscriptions...
D [16/Oct/2017:18:34:07 +0100] [Client 126] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:07 +0100] [Client 126] Waiting for request.
I [16/Oct/2017:18:34:07 +0100] Expiring subscriptions...
D [16/Oct/2017:18:34:07 +0100] [Client 126] POST / HTTP/1.1
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:34:07 +0100] [Client 126] Read: status=200
D [16/Oct/2017:18:34:07 +0100] [Client 126] No authentication data provided.
D [16/Oct/2017:18:34:07 +0100] [Client 126] 2.0 CUPS-Get-Printers 1
D [16/Oct/2017:18:34:07 +0100] CUPS-Get-Printers
D [16/Oct/2017:18:34:07 +0100] [Client 126] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [16/Oct/2017:18:34:07 +0100] [Client 126] Content-Length: 2745
D [16/Oct/2017:18:34:07 +0100] [Client 126] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:07 +0100] [Client 126] con->http=0x55f61aeff730
D [16/Oct/2017:18:34:07 +0100] [Client 126] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2745, response=0x55f61addd5d0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:07 +0100] [Client 126] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:07 +0100] [Client 126] bytes=0, http_state=0, data_remaining=2745
D [16/Oct/2017:18:34:07 +0100] [Client 126] Flushing write buffer.
D [16/Oct/2017:18:34:07 +0100] [Client 126] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:07 +0100] [Client 126] Waiting for request.
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:34:07 +0100] [Client 126] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:07 +0100] [Client 126] Closing connection.
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:34:07 +0100] [Client 127] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:07 +0100] [Client 127] Waiting for request.
D [16/Oct/2017:18:34:07 +0100] [Client 127] POST / HTTP/1.1
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:34:07 +0100] [Client 127] Read: status=200
D [16/Oct/2017:18:34:07 +0100] [Client 127] No authentication data provided.
D [16/Oct/2017:18:34:07 +0100] [Client 127] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 127] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 127] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 127] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 127] 2.0 CUPS-Get-Default 2
D [16/Oct/2017:18:34:07 +0100] CUPS-Get-Default
D [16/Oct/2017:18:34:07 +0100] [Client 127] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [16/Oct/2017:18:34:07 +0100] [Client 127] Content-Length: 10078
D [16/Oct/2017:18:34:07 +0100] [Client 127] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:07 +0100] [Client 127] con->http=0x55f61aeff730
D [16/Oct/2017:18:34:07 +0100] [Client 127] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=10078, response=0x55f61addd370(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:07 +0100] [Client 127] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:07 +0100] [Client 127] bytes=0, http_state=0, data_remaining=10078
D [16/Oct/2017:18:34:07 +0100] [Client 127] Flushing write buffer.
D [16/Oct/2017:18:34:07 +0100] [Client 127] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:07 +0100] [Client 127] Waiting for request.
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:34:07 +0100] [Client 127] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:07 +0100] [Client 127] Closing connection.
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:34:07 +0100] [Client 128] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:07 +0100] [Client 128] Waiting for request.
D [16/Oct/2017:18:34:07 +0100] [Client 128] POST / HTTP/1.1
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=200
D [16/Oct/2017:18:34:07 +0100] [Client 128] No authentication data provided.
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 128] 2.0 Get-Printer-Attributes 3
D [16/Oct/2017:18:34:07 +0100] Get-Printer-Attributes ipp://localhost:631/printers/Samsung-SCX-472x
D [16/Oct/2017:18:34:07 +0100] [Client 128] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/Samsung-SCX-472x) from localhost
D [16/Oct/2017:18:34:07 +0100] [Client 128] Content-Length: 1644
D [16/Oct/2017:18:34:07 +0100] [Client 128] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:07 +0100] [Client 128] con->http=0x55f61aeff730
D [16/Oct/2017:18:34:07 +0100] [Client 128] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1644, response=0x55f61addd5a0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:07 +0100] [Client 128] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:07 +0100] [Client 128] bytes=0, http_state=0, data_remaining=1644
D [16/Oct/2017:18:34:07 +0100] [Client 128] Flushing write buffer.
D [16/Oct/2017:18:34:07 +0100] [Client 128] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:07 +0100] [Client 128] Waiting for request.
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:34:07 +0100] [Client 128] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:07 +0100] [Client 128] Closing connection.
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:34:07 +0100] [Client 129] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:07 +0100] [Client 129] Waiting for request.
D [16/Oct/2017:18:34:07 +0100] [Client 129] POST / HTTP/1.1
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:34:07 +0100] [Client 129] Read: status=200
D [16/Oct/2017:18:34:07 +0100] [Client 129] No authentication data provided.
D [16/Oct/2017:18:34:07 +0100] [Client 129] 2.0 Get-Printer-Attributes 4
D [16/Oct/2017:18:34:07 +0100] Get-Printer-Attributes ipp://localhost:631/printers/Samsung-SCX-472x
D [16/Oct/2017:18:34:07 +0100] [Client 129] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/Samsung-SCX-472x) from localhost
D [16/Oct/2017:18:34:07 +0100] [Client 129] Content-Length: 1644
D [16/Oct/2017:18:34:07 +0100] [Client 129] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:07 +0100] [Client 129] con->http=0x55f61aeff730
D [16/Oct/2017:18:34:07 +0100] [Client 129] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1644, response=0x55f61addd820(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:07 +0100] [Client 129] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:07 +0100] [Client 129] bytes=0, http_state=0, data_remaining=1644
D [16/Oct/2017:18:34:07 +0100] [Client 129] Flushing write buffer.
D [16/Oct/2017:18:34:07 +0100] [Client 129] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:07 +0100] [Client 129] Waiting for request.
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:34:07 +0100] [Client 129] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:07 +0100] [Client 129] Closing connection.
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:34:07 +0100] [Client 130] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:07 +0100] [Client 130] Waiting for request.
D [16/Oct/2017:18:34:07 +0100] [Client 130] POST / HTTP/1.1
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:34:07 +0100] [Client 130] Read: status=200
D [16/Oct/2017:18:34:07 +0100] [Client 130] No authentication data provided.
D [16/Oct/2017:18:34:07 +0100] [Client 130] 2.0 Get-Printer-Attributes 5
D [16/Oct/2017:18:34:07 +0100] Get-Printer-Attributes ipp://localhost:631/printers/Samsung-SCX-472x
D [16/Oct/2017:18:34:07 +0100] [Client 130] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/Samsung-SCX-472x) from localhost
D [16/Oct/2017:18:34:07 +0100] [Client 130] Content-Length: 1644
D [16/Oct/2017:18:34:07 +0100] [Client 130] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:07 +0100] [Client 130] con->http=0x55f61aeff730
D [16/Oct/2017:18:34:07 +0100] [Client 130] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1644, response=0x55f61addd5a0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:07 +0100] [Client 130] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:07 +0100] [Client 130] bytes=0, http_state=0, data_remaining=1644
D [16/Oct/2017:18:34:07 +0100] [Client 130] Flushing write buffer.
D [16/Oct/2017:18:34:07 +0100] [Client 130] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:07 +0100] [Client 130] Waiting for request.
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:34:07 +0100] [Client 130] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:07 +0100] [Client 130] Closing connection.
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:34:07 +0100] [Client 131] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:07 +0100] [Client 131] Waiting for request.
D [16/Oct/2017:18:34:07 +0100] [Client 131] POST / HTTP/1.1
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:34:07 +0100] [Client 131] Read: status=200
D [16/Oct/2017:18:34:07 +0100] [Client 131] No authentication data provided.
D [16/Oct/2017:18:34:07 +0100] [Client 131] 2.0 Get-Printer-Attributes 6
D [16/Oct/2017:18:34:07 +0100] Get-Printer-Attributes ipp://localhost:631/printers/Samsung-SCX-472x
D [16/Oct/2017:18:34:07 +0100] [Client 131] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/Samsung-SCX-472x) from localhost
D [16/Oct/2017:18:34:07 +0100] [Client 131] Content-Length: 1644
D [16/Oct/2017:18:34:07 +0100] [Client 131] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:07 +0100] [Client 131] con->http=0x55f61aeff730
D [16/Oct/2017:18:34:07 +0100] [Client 131] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1644, response=0x55f61addd820(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:07 +0100] [Client 131] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:07 +0100] [Client 131] bytes=0, http_state=0, data_remaining=1644
D [16/Oct/2017:18:34:07 +0100] [Client 131] Flushing write buffer.
D [16/Oct/2017:18:34:07 +0100] [Client 131] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:07 +0100] [Client 131] Waiting for request.
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:34:07 +0100] [Client 132] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:07 +0100] [Client 132] Waiting for request.
D [16/Oct/2017:18:34:07 +0100] [Client 132] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:07 +0100] [Client 132] Closing connection.
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:34:07 +0100] [Client 133] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:07 +0100] [Client 133] Waiting for request.
D [16/Oct/2017:18:34:07 +0100] [Client 133] POST / HTTP/1.1
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=200
D [16/Oct/2017:18:34:07 +0100] [Client 133] No authentication data provided.
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] Read: status=100
D [16/Oct/2017:18:34:07 +0100] [Client 133] 2.0 CUPS-Get-Printers 1
D [16/Oct/2017:18:34:07 +0100] CUPS-Get-Printers
D [16/Oct/2017:18:34:07 +0100] [Client 133] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [16/Oct/2017:18:34:07 +0100] [Client 133] Content-Length: 1669
D [16/Oct/2017:18:34:07 +0100] [Client 133] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:07 +0100] [Client 133] con->http=0x55f61af03b00
D [16/Oct/2017:18:34:07 +0100] [Client 133] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1669, response=0x55f61addd610(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:07 +0100] [Client 133] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:07 +0100] [Client 133] bytes=0, http_state=0, data_remaining=1669
D [16/Oct/2017:18:34:07 +0100] [Client 133] Flushing write buffer.
D [16/Oct/2017:18:34:07 +0100] [Client 133] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:07 +0100] [Client 133] Waiting for request.
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:34:07 +0100] [Client 133] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:07 +0100] [Client 133] Closing connection.
D [16/Oct/2017:18:34:07 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [16/Oct/2017:18:34:08 +0100] Expiring subscriptions...
D [16/Oct/2017:18:34:15 +0100] [Client 134] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:15 +0100] [Client 134] Waiting for request.
I [16/Oct/2017:18:34:15 +0100] Expiring subscriptions...
D [16/Oct/2017:18:34:15 +0100] [Client 134] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:15 +0100] [Client 134] Closing connection.
D [16/Oct/2017:18:34:15 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:34:15 +0100] [Client 135] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:15 +0100] [Client 135] Waiting for request.
D [16/Oct/2017:18:34:15 +0100] [Client 135] POST / HTTP/1.1
D [16/Oct/2017:18:34:15 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=200
D [16/Oct/2017:18:34:15 +0100] [Client 135] No authentication data provided.
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] Read: status=100
D [16/Oct/2017:18:34:15 +0100] [Client 135] 2.0 CUPS-Get-Printers 1
D [16/Oct/2017:18:34:15 +0100] CUPS-Get-Printers
D [16/Oct/2017:18:34:15 +0100] [Client 135] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [16/Oct/2017:18:34:15 +0100] [Client 135] Content-Length: 1669
D [16/Oct/2017:18:34:15 +0100] [Client 135] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:15 +0100] [Client 135] con->http=0x55f61af03b00
D [16/Oct/2017:18:34:15 +0100] [Client 135] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1669, response=0x55f61addd890(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:15 +0100] [Client 135] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:15 +0100] [Client 135] bytes=0, http_state=0, data_remaining=1669
D [16/Oct/2017:18:34:15 +0100] [Client 135] Flushing write buffer.
D [16/Oct/2017:18:34:15 +0100] [Client 135] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:15 +0100] [Client 135] Waiting for request.
D [16/Oct/2017:18:34:15 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:34:15 +0100] [Client 135] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:15 +0100] [Client 135] Closing connection.
D [16/Oct/2017:18:34:15 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [16/Oct/2017:18:34:16 +0100] Expiring subscriptions...
D [16/Oct/2017:18:34:19 +0100] [Client 131] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:19 +0100] [Client 131] Closing connection.
D [16/Oct/2017:18:34:19 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [16/Oct/2017:18:34:19 +0100] Expiring subscriptions...
D [16/Oct/2017:18:34:19 +0100] [Client 136] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:19 +0100] [Client 136] Waiting for request.
D [16/Oct/2017:18:34:19 +0100] [Client 136] POST / HTTP/1.1
D [16/Oct/2017:18:34:19 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=200
D [16/Oct/2017:18:34:19 +0100] [Client 136] No authentication data provided.
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 136] 2.0 CUPS-Get-Printers 7
D [16/Oct/2017:18:34:19 +0100] CUPS-Get-Printers
D [16/Oct/2017:18:34:19 +0100] [Client 136] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [16/Oct/2017:18:34:19 +0100] [Client 136] Content-Length: 2745
D [16/Oct/2017:18:34:19 +0100] [Client 136] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:19 +0100] [Client 136] con->http=0x55f61aeff730
D [16/Oct/2017:18:34:19 +0100] [Client 136] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2745, response=0x55f61addd5d0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:19 +0100] [Client 136] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:19 +0100] [Client 136] bytes=0, http_state=0, data_remaining=2745
D [16/Oct/2017:18:34:19 +0100] [Client 136] Flushing write buffer.
D [16/Oct/2017:18:34:19 +0100] [Client 136] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:19 +0100] [Client 136] Waiting for request.
D [16/Oct/2017:18:34:19 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:34:19 +0100] [Client 136] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:19 +0100] [Client 136] Closing connection.
D [16/Oct/2017:18:34:19 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:34:19 +0100] [Client 137] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:19 +0100] [Client 137] Waiting for request.
D [16/Oct/2017:18:34:19 +0100] [Client 137] POST / HTTP/1.1
D [16/Oct/2017:18:34:19 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:34:19 +0100] [Client 137] Read: status=200
D [16/Oct/2017:18:34:19 +0100] [Client 137] No authentication data provided.
D [16/Oct/2017:18:34:19 +0100] [Client 137] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 137] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 137] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 137] Read: status=100
D [16/Oct/2017:18:34:19 +0100] [Client 137] 2.0 CUPS-Get-Default 8
D [16/Oct/2017:18:34:19 +0100] CUPS-Get-Default
D [16/Oct/2017:18:34:19 +0100] [Client 137] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [16/Oct/2017:18:34:19 +0100] [Client 137] Content-Length: 10078
D [16/Oct/2017:18:34:19 +0100] [Client 137] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:19 +0100] [Client 137] con->http=0x55f61aeff730
D [16/Oct/2017:18:34:19 +0100] [Client 137] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=10078, response=0x55f61addd370(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:19 +0100] [Client 137] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:19 +0100] [Client 137] bytes=0, http_state=0, data_remaining=10078
D [16/Oct/2017:18:34:19 +0100] [Client 137] Flushing write buffer.
D [16/Oct/2017:18:34:19 +0100] [Client 137] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:19 +0100] [Client 137] Waiting for request.
D [16/Oct/2017:18:34:19 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
I [16/Oct/2017:18:34:20 +0100] Expiring subscriptions...
D [16/Oct/2017:18:34:21 +0100] [Client 138] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:21 +0100] [Client 138] Waiting for request.
I [16/Oct/2017:18:34:21 +0100] Expiring subscriptions...
D [16/Oct/2017:18:34:21 +0100] [Client 139] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:21 +0100] [Client 139] Waiting for request.
D [16/Oct/2017:18:34:21 +0100] [Client 138] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:21 +0100] [Client 138] Closing connection.
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:34:21 +0100] [Client 139] POST / HTTP/1.1
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=200
D [16/Oct/2017:18:34:21 +0100] [Client 139] No authentication data provided.
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 139] 2.0 CUPS-Get-Printers 1
D [16/Oct/2017:18:34:21 +0100] CUPS-Get-Printers
D [16/Oct/2017:18:34:21 +0100] [Client 139] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [16/Oct/2017:18:34:21 +0100] [Client 139] Content-Length: 1669
D [16/Oct/2017:18:34:21 +0100] [Client 139] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:21 +0100] [Client 139] con->http=0x55f61af07ed0
D [16/Oct/2017:18:34:21 +0100] [Client 139] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1669, response=0x55f61addd610(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:21 +0100] [Client 139] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:21 +0100] [Client 139] bytes=0, http_state=0, data_remaining=1669
D [16/Oct/2017:18:34:21 +0100] [Client 139] Flushing write buffer.
D [16/Oct/2017:18:34:21 +0100] [Client 139] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:21 +0100] [Client 139] Waiting for request.
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:34:21 +0100] [Client 139] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:21 +0100] [Client 139] Closing connection.
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:34:21 +0100] [Client 137] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:21 +0100] [Client 137] Closing connection.
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:34:21 +0100] [Client 140] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:21 +0100] [Client 140] Waiting for request.
D [16/Oct/2017:18:34:21 +0100] [Client 140] POST / HTTP/1.1
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=200
D [16/Oct/2017:18:34:21 +0100] [Client 140] No authentication data provided.
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 140] 2.0 Get-Printer-Attributes 9
D [16/Oct/2017:18:34:21 +0100] Get-Printer-Attributes ipp://localhost:631/printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:34:21 +0100] [Client 140] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/HP-Deskjet-2540-series) from localhost
D [16/Oct/2017:18:34:21 +0100] [Client 140] Content-Length: 1176
D [16/Oct/2017:18:34:21 +0100] [Client 140] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:21 +0100] [Client 140] con->http=0x55f61aeff730
D [16/Oct/2017:18:34:21 +0100] [Client 140] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1176, response=0x55f61addd830(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:21 +0100] [Client 140] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:21 +0100] [Client 140] bytes=0, http_state=0, data_remaining=1176
D [16/Oct/2017:18:34:21 +0100] [Client 140] Flushing write buffer.
D [16/Oct/2017:18:34:21 +0100] [Client 140] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:21 +0100] [Client 140] Waiting for request.
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:34:21 +0100] [Client 140] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:21 +0100] [Client 140] Closing connection.
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:34:21 +0100] [Client 141] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:21 +0100] [Client 141] Waiting for request.
D [16/Oct/2017:18:34:21 +0100] [Client 141] POST / HTTP/1.1
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=200
D [16/Oct/2017:18:34:21 +0100] [Client 141] No authentication data provided.
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 141] 2.0 Get-Printer-Attributes 10
D [16/Oct/2017:18:34:21 +0100] Get-Printer-Attributes ipp://localhost:631/printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:34:21 +0100] [Client 141] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/HP-Deskjet-2540-series) from localhost
D [16/Oct/2017:18:34:21 +0100] [Client 141] Content-Length: 1176
D [16/Oct/2017:18:34:21 +0100] [Client 141] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:21 +0100] [Client 141] con->http=0x55f61aeff730
D [16/Oct/2017:18:34:21 +0100] [Client 141] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1176, response=0x55f61addd5b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:21 +0100] [Client 141] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:21 +0100] [Client 141] bytes=0, http_state=0, data_remaining=1176
D [16/Oct/2017:18:34:21 +0100] [Client 141] Flushing write buffer.
D [16/Oct/2017:18:34:21 +0100] [Client 141] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:21 +0100] [Client 141] Waiting for request.
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:34:21 +0100] [Client 141] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:21 +0100] [Client 141] Closing connection.
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:34:21 +0100] [Client 142] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:21 +0100] [Client 142] Waiting for request.
D [16/Oct/2017:18:34:21 +0100] [Client 142] POST / HTTP/1.1
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=200
D [16/Oct/2017:18:34:21 +0100] [Client 142] No authentication data provided.
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 142] 2.0 Get-Printer-Attributes 11
D [16/Oct/2017:18:34:21 +0100] Get-Printer-Attributes ipp://localhost:631/printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:34:21 +0100] [Client 142] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/HP-Deskjet-2540-series) from localhost
D [16/Oct/2017:18:34:21 +0100] [Client 142] Content-Length: 1176
D [16/Oct/2017:18:34:21 +0100] [Client 142] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:21 +0100] [Client 142] con->http=0x55f61aeff730
D [16/Oct/2017:18:34:21 +0100] [Client 142] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1176, response=0x55f61addd830(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:21 +0100] [Client 142] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:21 +0100] [Client 142] bytes=0, http_state=0, data_remaining=1176
D [16/Oct/2017:18:34:21 +0100] [Client 142] Flushing write buffer.
D [16/Oct/2017:18:34:21 +0100] [Client 142] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:21 +0100] [Client 142] Waiting for request.
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:34:21 +0100] [Client 142] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:21 +0100] [Client 142] Closing connection.
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:34:21 +0100] [Client 143] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:21 +0100] [Client 143] Waiting for request.
D [16/Oct/2017:18:34:21 +0100] [Client 143] POST / HTTP/1.1
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=200
D [16/Oct/2017:18:34:21 +0100] [Client 143] No authentication data provided.
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 143] 2.0 Get-Printer-Attributes 12
D [16/Oct/2017:18:34:21 +0100] Get-Printer-Attributes ipp://localhost:631/printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:34:21 +0100] [Client 143] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/HP-Deskjet-2540-series) from localhost
D [16/Oct/2017:18:34:21 +0100] [Client 143] Content-Length: 1176
D [16/Oct/2017:18:34:21 +0100] [Client 143] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:21 +0100] [Client 143] con->http=0x55f61aeff730
D [16/Oct/2017:18:34:21 +0100] [Client 143] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1176, response=0x55f61addd5b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:21 +0100] [Client 143] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:21 +0100] [Client 143] bytes=0, http_state=0, data_remaining=1176
D [16/Oct/2017:18:34:21 +0100] [Client 143] Flushing write buffer.
D [16/Oct/2017:18:34:21 +0100] [Client 143] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:21 +0100] [Client 143] Waiting for request.
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:34:21 +0100] [Client 144] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:21 +0100] [Client 144] Waiting for request.
D [16/Oct/2017:18:34:21 +0100] [Client 145] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:21 +0100] [Client 145] Waiting for request.
D [16/Oct/2017:18:34:21 +0100] [Client 144] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:21 +0100] [Client 144] Closing connection.
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:34:21 +0100] [Client 145] POST / HTTP/1.1
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:34:21 +0100] [Client 145] Read: status=200
D [16/Oct/2017:18:34:21 +0100] [Client 145] No authentication data provided.
D [16/Oct/2017:18:34:21 +0100] [Client 145] 2.0 CUPS-Get-Printers 1
D [16/Oct/2017:18:34:21 +0100] CUPS-Get-Printers
D [16/Oct/2017:18:34:21 +0100] [Client 145] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [16/Oct/2017:18:34:21 +0100] [Client 145] Content-Length: 1669
D [16/Oct/2017:18:34:21 +0100] [Client 145] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:21 +0100] [Client 145] con->http=0x55f61af07ed0
D [16/Oct/2017:18:34:21 +0100] [Client 145] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1669, response=0x55f61addd890(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:21 +0100] [Client 145] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:21 +0100] [Client 145] bytes=0, http_state=0, data_remaining=1669
D [16/Oct/2017:18:34:21 +0100] [Client 145] Flushing write buffer.
D [16/Oct/2017:18:34:21 +0100] [Client 145] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:21 +0100] [Client 145] Waiting for request.
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:34:21 +0100] [Client 145] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:21 +0100] [Client 145] Closing connection.
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:34:21 +0100] [Client 146] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:21 +0100] [Client 146] Waiting for request.
D [16/Oct/2017:18:34:21 +0100] [Client 147] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:21 +0100] [Client 147] Waiting for request.
D [16/Oct/2017:18:34:21 +0100] [Client 146] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:21 +0100] [Client 146] Closing connection.
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:34:21 +0100] [Client 148] Accepted from 192.168.101.11:52958 (IPv4)
D [16/Oct/2017:18:34:21 +0100] [Client 148] Waiting for request.
D [16/Oct/2017:18:34:21 +0100] [Client 147] POST / HTTP/1.1
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=200
D [16/Oct/2017:18:34:21 +0100] [Client 147] No authentication data provided.
D [16/Oct/2017:18:34:21 +0100] [Client 148] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:21 +0100] [Client 148] Closing connection.
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] Read: status=100
D [16/Oct/2017:18:34:21 +0100] [Client 147] 2.0 CUPS-Get-Printers 1
D [16/Oct/2017:18:34:21 +0100] CUPS-Get-Printers
D [16/Oct/2017:18:34:21 +0100] [Client 147] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [16/Oct/2017:18:34:21 +0100] [Client 147] Content-Length: 1669
D [16/Oct/2017:18:34:21 +0100] [Client 147] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:21 +0100] [Client 147] con->http=0x55f61af07ed0
D [16/Oct/2017:18:34:21 +0100] [Client 147] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1669, response=0x55f61addd510(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:21 +0100] [Client 147] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:21 +0100] [Client 147] bytes=0, http_state=0, data_remaining=1669
D [16/Oct/2017:18:34:21 +0100] [Client 147] Flushing write buffer.
D [16/Oct/2017:18:34:21 +0100] [Client 147] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:21 +0100] [Client 147] Waiting for request.
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:34:21 +0100] [Client 147] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:21 +0100] [Client 147] Closing connection.
D [16/Oct/2017:18:34:21 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [16/Oct/2017:18:34:22 +0100] Expiring subscriptions...
D [16/Oct/2017:18:34:26 +0100] [Client 149] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:26 +0100] [Client 149] Waiting for request.
I [16/Oct/2017:18:34:26 +0100] Expiring subscriptions...
D [16/Oct/2017:18:34:26 +0100] [Client 149] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:26 +0100] [Client 149] Closing connection.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:34:26 +0100] [Client 150] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:26 +0100] [Client 150] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] [Client 151] Accepted from 192.168.101.11:52960 (IPv4)
D [16/Oct/2017:18:34:26 +0100] [Client 151] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] [Client 150] POST / HTTP/1.1
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=200
D [16/Oct/2017:18:34:26 +0100] [Client 150] No authentication data provided.
D [16/Oct/2017:18:34:26 +0100] [Client 151] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:26 +0100] [Client 151] Closing connection.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 150] 2.0 CUPS-Get-Printers 1
D [16/Oct/2017:18:34:26 +0100] CUPS-Get-Printers
D [16/Oct/2017:18:34:26 +0100] [Client 150] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [16/Oct/2017:18:34:26 +0100] [Client 150] Content-Length: 1669
D [16/Oct/2017:18:34:26 +0100] [Client 150] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:26 +0100] [Client 150] con->http=0x55f61af03b00
D [16/Oct/2017:18:34:26 +0100] [Client 150] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1669, response=0x55f61addd790(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:26 +0100] [Client 150] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:26 +0100] [Client 150] bytes=0, http_state=0, data_remaining=1669
D [16/Oct/2017:18:34:26 +0100] [Client 150] Flushing write buffer.
D [16/Oct/2017:18:34:26 +0100] [Client 150] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:26 +0100] [Client 150] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Oct/2017:18:34:26 +0100] [Client 150] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:26 +0100] [Client 150] Closing connection.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Oct/2017:18:34:26 +0100] [Client 152] Accepted from 192.168.101.11:52962 (IPv4)
D [16/Oct/2017:18:34:26 +0100] [Client 152] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] [Client 152] POST /printers/HP-Deskjet-2540-series HTTP/1.1
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=200
D [16/Oct/2017:18:34:26 +0100] [Client 152] No authentication data provided.
D [16/Oct/2017:18:34:26 +0100] cupsdNetIFUpdate: Ignoring "lo".
D [16/Oct/2017:18:34:26 +0100] cupsdNetIFUpdate: Ignoring "eth0".
D [16/Oct/2017:18:34:26 +0100] cupsdNetIFUpdate: Ignoring "tun0".
D [16/Oct/2017:18:34:26 +0100] cupsdNetIFUpdate: "lo" = localhost:631
D [16/Oct/2017:18:34:26 +0100] cupsdNetIFUpdate: "eth0" = 192.168.101.11:631
D [16/Oct/2017:18:34:26 +0100] cupsdNetIFUpdate: "tun0" = 10.8.0.1:631
D [16/Oct/2017:18:34:26 +0100] cupsdNetIFUpdate: "lo" = localhost:631
D [16/Oct/2017:18:34:26 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::52e5:49ff:fe15:5752+eth0]:631
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] 2.1 Print-Job 1
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] [Client 152] Read: status=100
D [16/Oct/2017:18:34:26 +0100] Print-Job ipp://192.168.101.11:631/printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:34:26 +0100] [Job ???] Auto-typing file...
I [16/Oct/2017:18:34:26 +0100] [Job ???] Request file type is application/pdf.
D [16/Oct/2017:18:34:26 +0100] cupsdMarkDirty(---J-)
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [16/Oct/2017:18:34:26 +0100] add_job: requesting-user-name="john"
D [16/Oct/2017:18:34:26 +0100] Adding default job-sheets values "none,none"...
I [16/Oct/2017:18:34:26 +0100] [Job 1684] Adding start banner page "none".
D [16/Oct/2017:18:34:26 +0100] cupsdMarkDirty(----S)
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:34:26 +0100] cupsdMarkDirty(---J-)
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [16/Oct/2017:18:34:26 +0100] [Job 1684] Adding end banner page "none".
I [16/Oct/2017:18:34:26 +0100] [Job 1684] File of type application/pdf queued by "john".
D [16/Oct/2017:18:34:26 +0100] [Job 1684] hold_until=0
I [16/Oct/2017:18:34:26 +0100] [Job 1684] Queued on "HP-Deskjet-2540-series" by "john".
D [16/Oct/2017:18:34:26 +0100] [Client 152] Returning IPP successful-ok for Print-Job (ipp://192.168.101.11:631/printers/HP-Deskjet-2540-series) from 192.168.101.11
D [16/Oct/2017:18:34:26 +0100] [Client 152] Content-Length: 203
D [16/Oct/2017:18:34:26 +0100] [Client 152] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:26 +0100] [Client 152] con->http=0x55f61af03b00
D [16/Oct/2017:18:34:26 +0100] [Client 152] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=203, response=0x55f61addd7d0(IPP_STATE_IDLE), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:26 +0100] [Client 152] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:26 +0100] [Client 152] bytes=0, http_state=0, data_remaining=203
D [16/Oct/2017:18:34:26 +0100] [Client 152] Flushing write buffer.
D [16/Oct/2017:18:34:26 +0100] [Client 152] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:26 +0100] [Client 152] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:34:26 +0100] [Notifier] state=3
D [16/Oct/2017:18:34:26 +0100] [Notifier] state=3
D [16/Oct/2017:18:34:26 +0100] [Notifier] JobCreated
D [16/Oct/2017:18:34:26 +0100] [Notifier] state=3
D [16/Oct/2017:18:34:26 +0100] [Notifier] state=3
D [16/Oct/2017:18:34:26 +0100] [Client 153] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:26 +0100] [Client 153] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] [Client 154] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:26 +0100] [Client 154] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] [Client 153] POST / HTTP/1.1
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 153] Read: status=200
D [16/Oct/2017:18:34:26 +0100] [Client 153] No authentication data provided.
D [16/Oct/2017:18:34:26 +0100] [Client 153] 2.0 Get-Jobs 6
D [16/Oct/2017:18:34:26 +0100] Get-Jobs ipp://localhost/printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:34:26 +0100] [Client 153] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-Deskjet-2540-series) from localhost
D [16/Oct/2017:18:34:26 +0100] [Client 153] Content-Length: 75
D [16/Oct/2017:18:34:26 +0100] [Client 153] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:26 +0100] [Client 153] con->http=0x55f61af0c260
D [16/Oct/2017:18:34:26 +0100] [Client 153] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55f61af13ac0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:26 +0100] [Client 153] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:26 +0100] [Client 153] bytes=0, http_state=0, data_remaining=75
D [16/Oct/2017:18:34:26 +0100] [Client 153] Flushing write buffer.
D [16/Oct/2017:18:34:26 +0100] [Client 153] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:26 +0100] [Client 153] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 154] POST / HTTP/1.1
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 154] Read: status=200
D [16/Oct/2017:18:34:26 +0100] [Client 154] No authentication data provided.
D [16/Oct/2017:18:34:26 +0100] [Client 154] 2.0 Get-Jobs 6
D [16/Oct/2017:18:34:26 +0100] Get-Jobs ipp://localhost/printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:34:26 +0100] [Client 154] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-Deskjet-2540-series) from localhost
D [16/Oct/2017:18:34:26 +0100] [Client 154] Content-Length: 409
D [16/Oct/2017:18:34:26 +0100] [Client 154] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:26 +0100] [Client 154] con->http=0x55f61af10740
D [16/Oct/2017:18:34:26 +0100] [Client 154] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=409, response=0x55f61addd490(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:26 +0100] [Client 154] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:26 +0100] [Client 154] bytes=0, http_state=0, data_remaining=409
D [16/Oct/2017:18:34:26 +0100] [Client 154] Flushing write buffer.
D [16/Oct/2017:18:34:26 +0100] [Client 154] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:26 +0100] [Client 154] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 152] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:26 +0100] [Client 152] Closing connection.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 155] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:26 +0100] [Client 155] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] [Client 155] POST / HTTP/1.1
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 155] Read: status=200
D [16/Oct/2017:18:34:26 +0100] [Client 155] No authentication data provided.
D [16/Oct/2017:18:34:26 +0100] [Client 155] 2.0 Get-Notifications 13
D [16/Oct/2017:18:34:26 +0100] Get-Notifications /
D [16/Oct/2017:18:34:26 +0100] cupsdIsAuthorized: requesting-user-name="john"
D [16/Oct/2017:18:34:26 +0100] [Client 155] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [16/Oct/2017:18:34:26 +0100] [Client 155] Content-Length: 127
D [16/Oct/2017:18:34:26 +0100] [Client 155] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:26 +0100] [Client 155] con->http=0x55f61af03b00
D [16/Oct/2017:18:34:26 +0100] [Client 155] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x55f61aef91b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:26 +0100] [Client 155] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:26 +0100] [Client 155] bytes=0, http_state=0, data_remaining=127
D [16/Oct/2017:18:34:26 +0100] [Client 155] Flushing write buffer.
D [16/Oct/2017:18:34:26 +0100] [Client 155] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:26 +0100] [Client 155] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 155] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:26 +0100] [Client 155] Closing connection.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 156] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:26 +0100] [Client 156] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] [Client 156] POST / HTTP/1.1
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 156] Read: status=200
D [16/Oct/2017:18:34:26 +0100] [Client 156] No authentication data provided.
D [16/Oct/2017:18:34:26 +0100] [Client 156] 2.0 Get-Notifications 23
D [16/Oct/2017:18:34:26 +0100] Get-Notifications /
D [16/Oct/2017:18:34:26 +0100] cupsdIsAuthorized: requesting-user-name="john"
D [16/Oct/2017:18:34:26 +0100] [Client 156] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [16/Oct/2017:18:34:26 +0100] [Client 156] Content-Length: 127
D [16/Oct/2017:18:34:26 +0100] [Client 156] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:26 +0100] [Client 156] con->http=0x55f61af03b00
D [16/Oct/2017:18:34:26 +0100] [Client 156] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x55f61aef91b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:26 +0100] [Client 156] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:26 +0100] [Client 156] bytes=0, http_state=0, data_remaining=127
D [16/Oct/2017:18:34:26 +0100] [Client 156] Flushing write buffer.
D [16/Oct/2017:18:34:26 +0100] [Client 156] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:26 +0100] [Client 156] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 156] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:26 +0100] [Client 156] Closing connection.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 157] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:26 +0100] [Client 157] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] [Client 157] POST / HTTP/1.1
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 157] Read: status=200
D [16/Oct/2017:18:34:26 +0100] [Client 157] No authentication data provided.
D [16/Oct/2017:18:34:26 +0100] [Client 157] 2.0 Get-Notifications 24
D [16/Oct/2017:18:34:26 +0100] Get-Notifications /
D [16/Oct/2017:18:34:26 +0100] cupsdIsAuthorized: requesting-user-name="john"
D [16/Oct/2017:18:34:26 +0100] [Client 157] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [16/Oct/2017:18:34:26 +0100] [Client 157] Content-Length: 697
D [16/Oct/2017:18:34:26 +0100] [Client 157] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:26 +0100] [Client 157] con->http=0x55f61af03b00
D [16/Oct/2017:18:34:26 +0100] [Client 157] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=697, response=0x55f61aef91b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:26 +0100] [Client 157] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:26 +0100] [Client 157] bytes=0, http_state=0, data_remaining=697
D [16/Oct/2017:18:34:26 +0100] [Client 157] Flushing write buffer.
D [16/Oct/2017:18:34:26 +0100] [Client 157] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:26 +0100] [Client 157] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 157] POST / HTTP/1.1
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 157] Read: status=200
D [16/Oct/2017:18:34:26 +0100] [Client 157] No authentication data provided.
D [16/Oct/2017:18:34:26 +0100] [Client 157] 2.0 Get-Job-Attributes 25
D [16/Oct/2017:18:34:26 +0100] Get-Job-Attributes ipp://localhost/jobs/1684
D [16/Oct/2017:18:34:26 +0100] [Client 157] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/1684) from localhost
D [16/Oct/2017:18:34:26 +0100] [Client 157] Content-Length: 963
D [16/Oct/2017:18:34:26 +0100] [Client 157] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:26 +0100] [Client 157] con->http=0x55f61af03b00
D [16/Oct/2017:18:34:26 +0100] [Client 157] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=963, response=0x55f61addd490(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:26 +0100] [Client 157] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:26 +0100] [Client 157] bytes=0, http_state=0, data_remaining=963
D [16/Oct/2017:18:34:26 +0100] [Client 157] Flushing write buffer.
D [16/Oct/2017:18:34:26 +0100] [Client 157] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:26 +0100] [Client 157] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 158] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:26 +0100] [Client 158] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] [Client 158] POST / HTTP/1.1
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 158] Read: status=200
D [16/Oct/2017:18:34:26 +0100] [Client 158] No authentication data provided.
D [16/Oct/2017:18:34:26 +0100] [Client 158] 2.0 Get-Job-Attributes 26
D [16/Oct/2017:18:34:26 +0100] Get-Job-Attributes ipp://localhost/jobs/1684
D [16/Oct/2017:18:34:26 +0100] [Client 158] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/1684) from localhost
D [16/Oct/2017:18:34:26 +0100] [Client 158] Content-Length: 75
D [16/Oct/2017:18:34:26 +0100] [Client 158] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:26 +0100] [Client 158] con->http=0x55f61af14c20
D [16/Oct/2017:18:34:26 +0100] [Client 158] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55f61af17f30(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:26 +0100] [Client 158] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:26 +0100] [Client 158] bytes=0, http_state=0, data_remaining=75
D [16/Oct/2017:18:34:26 +0100] [Client 158] Flushing write buffer.
D [16/Oct/2017:18:34:26 +0100] [Client 158] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:26 +0100] [Client 158] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 158] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:26 +0100] [Client 158] Closing connection.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 159] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:26 +0100] [Client 159] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] [Client 159] POST / HTTP/1.1
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 159] Read: status=200
D [16/Oct/2017:18:34:26 +0100] [Client 159] No authentication data provided.
D [16/Oct/2017:18:34:26 +0100] [Client 159] 2.0 Get-Printer-Attributes 27
D [16/Oct/2017:18:34:26 +0100] Get-Printer-Attributes ipp://Desktop:631/printers/HP-Deskjet-2540-series
D [16/Oct/2017:18:34:26 +0100] [Client 159] Returning IPP successful-ok for Get-Printer-Attributes (ipp://Desktop:631/printers/HP-Deskjet-2540-series) from localhost
D [16/Oct/2017:18:34:26 +0100] [Client 159] Content-Length: 258
D [16/Oct/2017:18:34:26 +0100] [Client 159] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:26 +0100] [Client 159] con->http=0x55f61af14c20
D [16/Oct/2017:18:34:26 +0100] [Client 159] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=258, response=0x55f61af17ee0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:26 +0100] [Client 159] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:26 +0100] [Client 159] bytes=0, http_state=0, data_remaining=258
D [16/Oct/2017:18:34:26 +0100] [Client 159] Flushing write buffer.
D [16/Oct/2017:18:34:26 +0100] [Client 159] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:26 +0100] [Client 159] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 159] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:26 +0100] [Client 159] Closing connection.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 160] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:26 +0100] [Client 160] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] [Client 160] POST / HTTP/1.1
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 160] Read: status=200
D [16/Oct/2017:18:34:26 +0100] [Client 160] No authentication data provided.
D [16/Oct/2017:18:34:26 +0100] [Client 160] 2.0 Get-Job-Attributes 28
D [16/Oct/2017:18:34:26 +0100] Get-Job-Attributes ipp://localhost/jobs/1684
D [16/Oct/2017:18:34:26 +0100] [Client 160] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/1684) from localhost
D [16/Oct/2017:18:34:26 +0100] [Client 160] Content-Length: 75
D [16/Oct/2017:18:34:26 +0100] [Client 160] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:26 +0100] [Client 160] con->http=0x55f61af14c20
D [16/Oct/2017:18:34:26 +0100] [Client 160] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55f61af17f30(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:26 +0100] [Client 160] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:26 +0100] [Client 160] bytes=0, http_state=0, data_remaining=75
D [16/Oct/2017:18:34:26 +0100] [Client 160] Flushing write buffer.
D [16/Oct/2017:18:34:26 +0100] [Client 160] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:26 +0100] [Client 160] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 160] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:26 +0100] [Client 160] Closing connection.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 157] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:26 +0100] [Client 157] Closing connection.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 161] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:26 +0100] [Client 161] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] [Client 161] POST / HTTP/1.1
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 161] Read: status=200
D [16/Oct/2017:18:34:26 +0100] [Client 161] No authentication data provided.
D [16/Oct/2017:18:34:26 +0100] [Client 161] 2.0 Get-Notifications 29
D [16/Oct/2017:18:34:26 +0100] Get-Notifications /
D [16/Oct/2017:18:34:26 +0100] cupsdIsAuthorized: requesting-user-name="john"
D [16/Oct/2017:18:34:26 +0100] [Client 161] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [16/Oct/2017:18:34:26 +0100] [Client 161] Content-Length: 697
D [16/Oct/2017:18:34:26 +0100] [Client 161] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:26 +0100] [Client 161] con->http=0x55f61af03b00
D [16/Oct/2017:18:34:26 +0100] [Client 161] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=697, response=0x55f61aef91b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:26 +0100] [Client 161] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:26 +0100] [Client 161] bytes=0, http_state=0, data_remaining=697
D [16/Oct/2017:18:34:26 +0100] [Client 161] Flushing write buffer.
D [16/Oct/2017:18:34:26 +0100] [Client 161] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:26 +0100] [Client 161] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 161] POST / HTTP/1.1
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 161] Read: status=200
D [16/Oct/2017:18:34:26 +0100] [Client 161] No authentication data provided.
D [16/Oct/2017:18:34:26 +0100] [Client 161] 2.0 Get-Job-Attributes 30
D [16/Oct/2017:18:34:26 +0100] Get-Job-Attributes ipp://localhost/jobs/1684
D [16/Oct/2017:18:34:26 +0100] [Client 161] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/1684) from localhost
D [16/Oct/2017:18:34:26 +0100] [Client 161] Content-Length: 963
D [16/Oct/2017:18:34:26 +0100] [Client 161] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:26 +0100] [Client 161] con->http=0x55f61af03b00
D [16/Oct/2017:18:34:26 +0100] [Client 161] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=963, response=0x55f61addd490(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:26 +0100] [Client 161] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:26 +0100] [Client 161] bytes=0, http_state=0, data_remaining=963
D [16/Oct/2017:18:34:26 +0100] [Client 161] Flushing write buffer.
D [16/Oct/2017:18:34:26 +0100] [Client 161] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:26 +0100] [Client 161] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 162] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:26 +0100] [Client 162] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] [Client 162] POST / HTTP/1.1
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 162] Read: status=200
D [16/Oct/2017:18:34:26 +0100] [Client 162] No authentication data provided.
D [16/Oct/2017:18:34:26 +0100] [Client 162] 2.0 Get-Printer-Attributes 31
D [16/Oct/2017:18:34:26 +0100] Get-Printer-Attributes 
D [16/Oct/2017:18:34:26 +0100] Get-Printer-Attributes client-error-not-found: The printer or class does not exist.
D [16/Oct/2017:18:34:26 +0100] [Client 162] Returning IPP client-error-not-found for Get-Printer-Attributes () from localhost
D [16/Oct/2017:18:34:26 +0100] [Client 162] Content-Length: 130
D [16/Oct/2017:18:34:26 +0100] [Client 162] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:26 +0100] [Client 162] con->http=0x55f61af14c20
D [16/Oct/2017:18:34:26 +0100] [Client 162] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=130, response=0x55f61af17ee0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:26 +0100] [Client 162] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:26 +0100] [Client 162] bytes=0, http_state=0, data_remaining=130
D [16/Oct/2017:18:34:26 +0100] [Client 162] Flushing write buffer.
D [16/Oct/2017:18:34:26 +0100] [Client 162] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:26 +0100] [Client 162] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 162] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:26 +0100] [Client 162] Closing connection.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 163] Accepted from localhost (Domain)
D [16/Oct/2017:18:34:26 +0100] [Client 163] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] [Client 163] POST / HTTP/1.1
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 163] Read: status=200
D [16/Oct/2017:18:34:26 +0100] [Client 163] No authentication data provided.
D [16/Oct/2017:18:34:26 +0100] [Client 163] 2.0 Get-Job-Attributes 32
D [16/Oct/2017:18:34:26 +0100] Get-Job-Attributes ipp://localhost/jobs/1684
D [16/Oct/2017:18:34:26 +0100] [Client 163] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/1684) from localhost
D [16/Oct/2017:18:34:26 +0100] [Client 163] Content-Length: 101
D [16/Oct/2017:18:34:26 +0100] [Client 163] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [16/Oct/2017:18:34:26 +0100] [Client 163] con->http=0x55f61af14c20
D [16/Oct/2017:18:34:26 +0100] [Client 163] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=101, response=0x55f61af17f30(IPP_STATE_DATA), pipe_pid=0, file=-1
D [16/Oct/2017:18:34:26 +0100] [Client 163] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [16/Oct/2017:18:34:26 +0100] [Client 163] bytes=0, http_state=0, data_remaining=101
D [16/Oct/2017:18:34:26 +0100] [Client 163] Flushing write buffer.
D [16/Oct/2017:18:34:26 +0100] [Client 163] New state is HTTP_STATE_WAITING
D [16/Oct/2017:18:34:26 +0100] [Client 163] Waiting for request.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 163] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:26 +0100] [Client 163] Closing connection.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [16/Oct/2017:18:34:26 +0100] [Client 161] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/Oct/2017:18:34:26 +0100] [Client 161] Closing connection.
D [16/Oct/2017:18:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [16/Oct/2017:18:34:27 +0100] Expiring subscriptions...
I [16/Oct/2017:18:34:38 +0100] Expiring subscriptions...
D [16/Oct/2017:18:34:38 +0100] Report: clients=4
D [16/Oct/2017:18:34:38 +0100] Report: jobs=481
D [16/Oct/2017:18:34:38 +0100] Report: jobs-active=1
D [16/Oct/2017:18:34:38 +0100] Report: printers=2
D [16/Oct/2017:18:34:38 +0100] Report: stringpool-string-count=3125
D [16/Oct/2017:18:34:38 +0100] Report: stringpool-alloc-bytes=12304
D [16/Oct/2017:18:34:38 +0100] Report: stringpool-total-bytes=55928
I [16/Oct/2017:18:34:49 +0100] Expiring subscriptions...
I [16/Oct/2017:18:34:57 +0100] Saving job.cache...
I [16/Oct/2017:18:34:57 +0100] Saving subscriptions.conf...
D [16/Oct/2017:18:34:57 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
I [16/Oct/2017:18:34:57 +0100] Expiring subscriptions...
I [16/Oct/2017:18:35:08 +0100] Expiring subscriptions...
I [16/Oct/2017:18:35:19 +0100] Expiring subscriptions...
I [16/Oct/2017:18:35:30 +0100] Expiring subscriptions...
I [16/Oct/2017:18:35:41 +0100] Expiring subscriptions...
D [16/Oct/2017:18:35:41 +0100] Report: clients=4
D [16/Oct/2017:18:35:41 +0100] Report: jobs=481
D [16/Oct/2017:18:35:41 +0100] Report: jobs-active=1
D [16/Oct/2017:18:35:41 +0100] Report: printers=2
D [16/Oct/2017:18:35:41 +0100] Report: stringpool-string-count=3125
D [16/Oct/2017:18:35:41 +0100] Report: stringpool-alloc-bytes=12304
D [16/Oct/2017:18:35:41 +0100] Report: stringpool-total-bytes=55928
I [16/Oct/2017:18:35:52 +0100] Expiring subscriptions...
I [16/Oct/2017:18:36:03 +0100] Expiring subscriptions...
I [16/Oct/2017:18:36:14 +0100] Expiring subscriptions...
I [16/Oct/2017:18:36:25 +0100] Expiring subscriptions...
I [16/Oct/2017:18:36:36 +0100] Expiring subscriptions...
I [16/Oct/2017:18:36:47 +0100] Expiring subscriptions...
D [16/Oct/2017:18:36:47 +0100] Report: clients=4
D [16/Oct/2017:18:36:47 +0100] Report: jobs=481
D [16/Oct/2017:18:36:47 +0100] Report: jobs-active=1
D [16/Oct/2017:18:36:47 +0100] Report: printers=2
D [16/Oct/2017:18:36:47 +0100] Report: stringpool-string-count=3125
D [16/Oct/2017:18:36:47 +0100] Report: stringpool-alloc-bytes=12304
D [16/Oct/2017:18:36:47 +0100] Report: stringpool-total-bytes=55928
I [16/Oct/2017:18:36:58 +0100] Expiring subscriptions...
I [16/Oct/2017:18:37:09 +0100] Expiring subscriptions...
I [16/Oct/2017:18:37:20 +0100] Expiring subscriptions...
I [16/Oct/2017:18:37:31 +0100] Expiring subscriptions...
I [16/Oct/2017:18:37:42 +0100] Expiring subscriptions...
I [16/Oct/2017:18:37:53 +0100] Expiring subscriptions...
D [16/Oct/2017:18:37:53 +0100] Report: clients=4
D [16/Oct/2017:18:37:53 +0100] Report: jobs=481
D [16/Oct/2017:18:37:53 +0100] Report: jobs-active=1
D [16/Oct/2017:18:37:53 +0100] Report: printers=2
D [16/Oct/2017:18:37:53 +0100] Report: stringpool-string-count=3125
D [16/Oct/2017:18:37:53 +0100] Report: stringpool-alloc-bytes=12304
D [16/Oct/2017:18:37:53 +0100] Report: stringpool-total-bytes=55928
I [16/Oct/2017:18:38:04 +0100] Expiring subscriptions...
I [16/Oct/2017:18:38:15 +0100] Expiring subscriptions...