Kod: Zaznacz cały
I [13/Nov/2017:16:31:56 +0100] Saving subscriptions.conf...
D [13/Nov/2017:16:31:56 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [13/Nov/2017:16:31:56 +0100] Creating keep-alive file "/var/cache/cups/org.cups.cupsd".
I [13/Nov/2017:16:31:56 +0100] Saving job.cache...
D [13/Nov/2017:16:31:56 +0100] [Job 40] Unloading...
D [13/Nov/2017:16:31:56 +0100] [Job 41] Unloading...
D [13/Nov/2017:16:31:56 +0100] cupsdStopSelect()
I [13/Nov/2017:16:31:57 +0100] Listening to 0.0.0.0:631 (IPv4)
I [13/Nov/2017:16:31:57 +0100] Listening to [v1.::]:631 (IPv6)
I [13/Nov/2017:16:31:57 +0100] Listening to /var/run/cups/cups.sock (Domain)
E [13/Nov/2017:16:31:57 +0100] Unknown directive JobPrivateAccess on line 93 of /etc/cups/cupsd.conf.
E [13/Nov/2017:16:31:57 +0100] Unknown directive JobPrivateValues on line 94 of /etc/cups/cupsd.conf.
E [13/Nov/2017:16:31:57 +0100] Unknown directive SubscriptionPrivateAccess on line 95 of /etc/cups/cupsd.conf.
E [13/Nov/2017:16:31:57 +0100] Unknown directive SubscriptionPrivateValues on line 96 of /etc/cups/cupsd.conf.
I [13/Nov/2017:16:31:57 +0100] Remote access is enabled.
D [13/Nov/2017:16:31:57 +0100] Added auto ServerAlias Tata-Mint
I [13/Nov/2017:16:31:57 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
D [13/Nov/2017:16:31:57 +0100] Using keychain "/etc/cups/ssl" for server name "Tata-Mint".
I [13/Nov/2017:16:31:57 +0100] Using default TempDir of /var/spool/cups/tmp...
I [13/Nov/2017:16:31:57 +0100] Configured for up to 100 clients.
I [13/Nov/2017:16:31:57 +0100] Allowing up to 100 client connections per host.
I [13/Nov/2017:16:31:57 +0100] Using policy "default" as the default.
I [13/Nov/2017:16:31:57 +0100] Full reload is required.
I [13/Nov/2017:16:31:57 +0100] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 49 types, 83 filters...
D [13/Nov/2017:16:31:57 +0100] Loading printer Lexmark_Pro200S500_Series...
D [13/Nov/2017:16:31:57 +0100] load_ppd: Loading /var/cache/cups/Lexmark_Pro200S500_Series.data...
D [13/Nov/2017:16:31:57 +0100] cupsdRegisterPrinter(p=0x55efabe6ebe0(Lexmark_Pro200S500_Series))
I [13/Nov/2017:16:31:57 +0100] Loading job cache file "/var/cache/cups/job.cache"...
D [13/Nov/2017:16:31:57 +0100] [Job 40] Loading from cache...
D [13/Nov/2017:16:31:57 +0100] [Job 41] Loading from cache...
D [13/Nov/2017:16:31:57 +0100] [Job 41] Loading attributes...
D [13/Nov/2017:16:31:57 +0100] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
D [13/Nov/2017:16:31:57 +0100] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
D [13/Nov/2017:16:31:57 +0100] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
I [13/Nov/2017:16:31:57 +0100] Full reload complete.
D [13/Nov/2017:16:31:57 +0100] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
I [13/Nov/2017:16:31:57 +0100] Cleaning out old files in "/var/spool/cups/tmp".
D [13/Nov/2017:16:31:57 +0100] cupsdCleanFiles(path="/var/spool/cups/tmp/.hplip", pattern="(null)")
I [13/Nov/2017:16:31:57 +0100] Cleaning out old files in "/var/spool/cups/tmp/.hplip".
D [13/Nov/2017:16:31:57 +0100] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
I [13/Nov/2017:16:31:57 +0100] Cleaning out old files in "/var/cache/cups".
D [13/Nov/2017:16:31:57 +0100] service_checkin: pid=2792
D [13/Nov/2017:16:31:57 +0100] service_checkin: 1 listeners.
D [13/Nov/2017:16:31:57 +0100] service_checkin: Listener #1 at fd 3, "/var/run/cups/cups.sock".
D [13/Nov/2017:16:31:57 +0100] service_checkin: Matched existing listener #1 to /var/run/cups/cups.sock.
D [13/Nov/2017:16:31:57 +0100] Creating keep-alive file "/var/cache/cups/org.cups.cupsd".
D [13/Nov/2017:16:31:57 +0100] Calling FindDeviceById(cups-Lexmark_Pro200S500_Series)
D [13/Nov/2017:16:31:57 +0100] FindDeviceById failed: org.freedesktop.ColorManager.NotFound:device id \'cups-Lexmark_Pro200S500_Series\' does not exist
D [13/Nov/2017:16:31:57 +0100] Using profile ID "Lexmark_Pro200S500_Series-Gray..".
D [13/Nov/2017:16:31:57 +0100] Calling CreateProfile(Lexmark_Pro200S500_Series-Gray..,temp)
D [13/Nov/2017:16:31:57 +0100] Created profile "/org/freedesktop/ColorManager/profiles/Lexmark_Pro200S500_Series_Gray__".
D [13/Nov/2017:16:31:57 +0100] Using profile ID "Lexmark_Pro200S500_Series-RGB..".
D [13/Nov/2017:16:31:57 +0100] Calling CreateProfile(Lexmark_Pro200S500_Series-RGB..,temp)
D [13/Nov/2017:16:31:57 +0100] Created profile "/org/freedesktop/ColorManager/profiles/Lexmark_Pro200S500_Series_RGB__".
I [13/Nov/2017:16:31:57 +0100] Registering ICC color profiles for "Lexmark_Pro200S500_Series".
D [13/Nov/2017:16:31:57 +0100] Calling CreateDevice(cups-Lexmark_Pro200S500_Series,temp)
D [13/Nov/2017:16:31:57 +0100] Created device "/org/freedesktop/ColorManager/devices/cups_Lexmark_Pro200S500_Series".
D [13/Nov/2017:16:31:57 +0100] Calling /org/freedesktop/ColorManager/devices/cups_Lexmark_Pro200S500_Series:AddProfile(/org/freedesktop/ColorManager/profiles/Lexmark_Pro200S500_Series_Gray__) [soft]
D [13/Nov/2017:16:31:57 +0100] Calling /org/freedesktop/ColorManager/devices/cups_Lexmark_Pro200S500_Series:AddProfile(/org/freedesktop/ColorManager/profiles/Lexmark_Pro200S500_Series_RGB__) [soft]
I [13/Nov/2017:16:31:57 +0100] Listening to 0.0.0.0:631 on fd 10...
I [13/Nov/2017:16:31:57 +0100] Listening to [v1.::]:631 on fd 11...
I [13/Nov/2017:16:31:57 +0100] Listening to /var/run/cups/cups.sock on fd 3...
I [13/Nov/2017:16:31:57 +0100] Resuming new connection processing...
D [13/Nov/2017:16:31:57 +0100] Avahi server connection now available, registering printers for Bonjour broadcasting.
D [13/Nov/2017:16:31:57 +0100] Registering "Lexmark_Pro200S500_Series @ Tata-Mint" with DNS-SD type "_printer._tcp".
D [13/Nov/2017:16:31:57 +0100] Registering "Lexmark_Pro200S500_Series @ Tata-Mint" with DNS-SD type "_ipps._tcp".
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" printer-type=0xF01E
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" printer-state=3
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" Duplex=T
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" Color=T
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" TLS=1.2
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" UUID=9b6e32d0-7b4a-32ea-5c27-d1c5e474f8b5
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" URF=DM3
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster,image/urf
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" product=(Lexmark Pro200-S500 Series)
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" priority=0
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" adminurl=https://Tata-Mint.local:631/printers/Lexmark_Pro200S500_Series
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" ty=Lexmark Pro200-S500 Series, 1.0
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" rp=printers/Lexmark_Pro200S500_Series
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" qtotal=1
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" txtvers=1
D [13/Nov/2017:16:31:57 +0100] Registering "Lexmark_Pro200S500_Series @ Tata-Mint" with DNS-SD type "_ipp._tcp".
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" printer-type=0xF01E
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" printer-state=3
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" Duplex=T
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" Color=T
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" TLS=1.2
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" UUID=9b6e32d0-7b4a-32ea-5c27-d1c5e474f8b5
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" URF=DM3
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster,image/urf
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" product=(Lexmark Pro200-S500 Series)
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" priority=0
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" adminurl=https://Tata-Mint.local:631/printers/Lexmark_Pro200S500_Series
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" ty=Lexmark Pro200-S500 Series, 1.0
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" rp=printers/Lexmark_Pro200S500_Series
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" qtotal=1
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" txtvers=1
D [13/Nov/2017:16:31:57 +0100] Registering "Lexmark_Pro200S500_Series @ Tata-Mint" with DNS-SD type "_printer._tcp".
D [13/Nov/2017:16:31:57 +0100] Registering "Lexmark_Pro200S500_Series @ Tata-Mint" with DNS-SD type "_ipps._tcp".
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" printer-type=0xF01E
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" printer-state=3
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" Duplex=T
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" Color=T
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" TLS=1.2
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" UUID=9b6e32d0-7b4a-32ea-5c27-d1c5e474f8b5
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" URF=DM3
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster,image/urf
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" product=(Lexmark Pro200-S500 Series)
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" priority=0
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" adminurl=https://Tata-Mint.local:631/printers/Lexmark_Pro200S500_Series
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" ty=Lexmark Pro200-S500 Series, 1.0
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" rp=printers/Lexmark_Pro200S500_Series
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" qtotal=1
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" txtvers=1
D [13/Nov/2017:16:31:57 +0100] Registering "Lexmark_Pro200S500_Series @ Tata-Mint" with DNS-SD type "_ipp._tcp".
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" printer-type=0xF01E
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" printer-state=3
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" Duplex=T
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" Color=T
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" TLS=1.2
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" UUID=9b6e32d0-7b4a-32ea-5c27-d1c5e474f8b5
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" URF=DM3
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster,image/urf
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" product=(Lexmark Pro200-S500 Series)
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" priority=0
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" adminurl=https://Tata-Mint.local:631/printers/Lexmark_Pro200S500_Series
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" ty=Lexmark Pro200-S500 Series, 1.0
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" rp=printers/Lexmark_Pro200S500_Series
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" qtotal=1
D [13/Nov/2017:16:31:57 +0100] DNS_SD "Lexmark_Pro200S500_Series @ Tata-Mint" txtvers=1
D [13/Nov/2017:16:31:57 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [13/Nov/2017:16:31:57 +0100] cupsdAddCert: Adding certificate for PID 0
D [13/Nov/2017:16:31:57 +0100] Discarding unused server-started event...
D [13/Nov/2017:16:31:57 +0100] [Client 1] Accepted from localhost (Domain)
D [13/Nov/2017:16:31:57 +0100] [Client 1] Waiting for request.
D [13/Nov/2017:16:31:57 +0100] Report: clients=1
D [13/Nov/2017:16:31:57 +0100] Report: jobs=2
D [13/Nov/2017:16:31:57 +0100] Report: jobs-active=1
D [13/Nov/2017:16:31:57 +0100] Report: printers=1
D [13/Nov/2017:16:31:57 +0100] Report: stringpool-string-count=8807
D [13/Nov/2017:16:31:57 +0100] Report: stringpool-alloc-bytes=11488
D [13/Nov/2017:16:31:57 +0100] Report: stringpool-total-bytes=167960
D [13/Nov/2017:16:31:57 +0100] [Client 1] POST / HTTP/1.1
D [13/Nov/2017:16:31:57 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [13/Nov/2017:16:31:57 +0100] [Client 1] Read: status=200
D [13/Nov/2017:16:31:57 +0100] [Client 1] No authentication data provided.
D [13/Nov/2017:16:31:57 +0100] [Client 1] 2.0 Create-Printer-Subscriptions 1
D [13/Nov/2017:16:31:57 +0100] Create-Printer-Subscriptions /
D [13/Nov/2017:16:31:57 +0100] create_subscriptions(con=0x55efabf43ee0(1), uri="/")
D [13/Nov/2017:16:31:57 +0100] pullmethod="ippget"
D [13/Nov/2017:16:31:57 +0100] notify-lease-duration=86400
D [13/Nov/2017:16:31:57 +0100] notify-time-interval=60
D [13/Nov/2017:16:31:57 +0100] cupsdAddSubscription(mask=7ff, dest=(nil)(), job=(nil)(0), uri="(null)")
D [13/Nov/2017:16:31:57 +0100] Added subscription #220 for server.
D [13/Nov/2017:16:31:57 +0100] cupsdMarkDirty(----S)
D [13/Nov/2017:16:31:57 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [13/Nov/2017:16:31:57 +0100] [Client 1] Returning IPP successful-ok for Create-Printer-Subscriptions (/) from localhost
D [13/Nov/2017:16:31:57 +0100] [Client 1] Content-Length: 107
D [13/Nov/2017:16:31:57 +0100] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [13/Nov/2017:16:31:57 +0100] [Client 1] con->http=0x55efabf450a0
D [13/Nov/2017:16:31:57 +0100] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=107, response=0x55efabe82580(IPP_STATE_DATA), pipe_pid=0, file=-1
D [13/Nov/2017:16:31:57 +0100] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [13/Nov/2017:16:31:57 +0100] [Client 1] bytes=0, http_state=0, data_remaining=107
D [13/Nov/2017:16:31:57 +0100] [Client 1] Flushing write buffer.
D [13/Nov/2017:16:31:57 +0100] [Client 1] New state is HTTP_STATE_WAITING
D [13/Nov/2017:16:31:57 +0100] [Client 1] Waiting for request.
D [13/Nov/2017:16:31:57 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [13/Nov/2017:16:31:57 +0100] [Client 2] Accepted from localhost (Domain)
D [13/Nov/2017:16:31:57 +0100] [Client 2] Waiting for request.
D [13/Nov/2017:16:31:57 +0100] [Client 2] POST / HTTP/1.1
D [13/Nov/2017:16:31:57 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [13/Nov/2017:16:31:57 +0100] [Client 2] Read: status=200
D [13/Nov/2017:16:31:57 +0100] [Client 2] No authentication data provided.
D [13/Nov/2017:16:31:57 +0100] [Client 2] 2.0 CUPS-Get-Printers 2
D [13/Nov/2017:16:31:57 +0100] CUPS-Get-Printers
D [13/Nov/2017:16:31:57 +0100] [Client 2] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [13/Nov/2017:16:31:57 +0100] [Client 2] Content-Length: 1109
D [13/Nov/2017:16:31:57 +0100] [Client 2] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [13/Nov/2017:16:31:57 +0100] [Client 2] con->http=0x55efabf49470
D [13/Nov/2017:16:31:57 +0100] [Client 2] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1109, response=0x55efabf41b30(IPP_STATE_DATA), pipe_pid=0, file=-1
D [13/Nov/2017:16:31:57 +0100] [Client 2] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [13/Nov/2017:16:31:57 +0100] [Client 2] bytes=0, http_state=0, data_remaining=1109
D [13/Nov/2017:16:31:57 +0100] [Client 2] Flushing write buffer.
D [13/Nov/2017:16:31:57 +0100] [Client 2] New state is HTTP_STATE_WAITING
D [13/Nov/2017:16:31:57 +0100] [Client 2] Waiting for request.
D [13/Nov/2017:16:31:57 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [13/Nov/2017:16:31:57 +0100] [Client 2] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [13/Nov/2017:16:31:57 +0100] [Client 2] Closing connection.
D [13/Nov/2017:16:31:57 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [13/Nov/2017:16:31:57 +0100] [Client 3] Accepted from localhost (Domain)
D [13/Nov/2017:16:31:57 +0100] [Client 3] Waiting for request.
D [13/Nov/2017:16:31:57 +0100] [Client 3] POST / HTTP/1.1
D [13/Nov/2017:16:31:57 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [13/Nov/2017:16:31:57 +0100] [Client 3] Read: status=200
D [13/Nov/2017:16:31:57 +0100] [Client 3] No authentication data provided.
D [13/Nov/2017:16:31:57 +0100] [Client 3] 2.0 CUPS-Get-Default 3
D [13/Nov/2017:16:31:57 +0100] CUPS-Get-Default
D [13/Nov/2017:16:31:57 +0100] [Client 3] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [13/Nov/2017:16:31:57 +0100] [Client 3] Content-Length: 12910
D [13/Nov/2017:16:31:57 +0100] [Client 3] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [13/Nov/2017:16:31:57 +0100] [Client 3] con->http=0x55efabf482b0
D [13/Nov/2017:16:31:57 +0100] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=12910, response=0x55efabf3ece0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [13/Nov/2017:16:31:57 +0100] [Client 3] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [13/Nov/2017:16:31:57 +0100] [Client 3] bytes=0, http_state=0, data_remaining=12910
D [13/Nov/2017:16:31:57 +0100] [Client 3] Flushing write buffer.
D [13/Nov/2017:16:31:57 +0100] [Client 3] New state is HTTP_STATE_WAITING
D [13/Nov/2017:16:31:57 +0100] [Client 3] Waiting for request.
D [13/Nov/2017:16:31:57 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [13/Nov/2017:16:31:57 +0100] [Client 3] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [13/Nov/2017:16:31:57 +0100] [Client 3] Closing connection.
D [13/Nov/2017:16:31:57 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [13/Nov/2017:16:31:57 +0100] [Client 4] Accepted from localhost (Domain)
D [13/Nov/2017:16:31:57 +0100] [Client 4] Waiting for request.
D [13/Nov/2017:16:31:57 +0100] [Client 4] POST / HTTP/1.1
D [13/Nov/2017:16:31:57 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [13/Nov/2017:16:31:57 +0100] [Client 4] Read: status=200
D [13/Nov/2017:16:31:57 +0100] [Client 4] No authentication data provided.
D [13/Nov/2017:16:31:57 +0100] [Client 4] 2.0 CUPS-Get-Default 4
D [13/Nov/2017:16:31:57 +0100] CUPS-Get-Default
D [13/Nov/2017:16:31:57 +0100] [Client 4] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [13/Nov/2017:16:31:57 +0100] [Client 4] Content-Length: 12910
D [13/Nov/2017:16:31:57 +0100] [Client 4] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [13/Nov/2017:16:31:57 +0100] [Client 4] con->http=0x55efabf482b0
D [13/Nov/2017:16:31:57 +0100] [Client 4] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=12910, response=0x55efabf3ece0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [13/Nov/2017:16:31:57 +0100] [Client 4] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [13/Nov/2017:16:31:57 +0100] [Client 4] bytes=0, http_state=0, data_remaining=12910
D [13/Nov/2017:16:31:57 +0100] [Client 4] Flushing write buffer.
D [13/Nov/2017:16:31:57 +0100] [Client 4] New state is HTTP_STATE_WAITING
D [13/Nov/2017:16:31:57 +0100] [Client 4] Waiting for request.
D [13/Nov/2017:16:31:57 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [13/Nov/2017:16:31:57 +0100] [Client 4] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [13/Nov/2017:16:31:57 +0100] [Client 4] Closing connection.
D [13/Nov/2017:16:31:57 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [13/Nov/2017:16:31:57 +0100] [Client 5] Accepted from localhost (Domain)
D [13/Nov/2017:16:31:57 +0100] [Client 5] Waiting for request.
D [13/Nov/2017:16:31:57 +0100] [Client 5] POST / HTTP/1.1
D [13/Nov/2017:16:31:57 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [13/Nov/2017:16:31:57 +0100] [Client 5] Read: status=200
D [13/Nov/2017:16:31:57 +0100] [Client 5] No authentication data provided.
D [13/Nov/2017:16:31:57 +0100] [Client 5] 2.0 Create-Printer-Subscriptions 5
D [13/Nov/2017:16:31:57 +0100] Create-Printer-Subscriptions /
D [13/Nov/2017:16:31:57 +0100] create_subscriptions(con=0x55efabf4c8a0(5), uri="/")
D [13/Nov/2017:16:31:57 +0100] recipient="dbus://"
D [13/Nov/2017:16:31:57 +0100] notify-lease-duration=86400
D [13/Nov/2017:16:31:57 +0100] notify-time-interval=0
D [13/Nov/2017:16:31:57 +0100] cupsdAddSubscription(mask=1fffff, dest=(nil)(), job=(nil)(0), uri="dbus://")
D [13/Nov/2017:16:31:57 +0100] Added subscription #221 for server.
D [13/Nov/2017:16:31:57 +0100] cupsdMarkDirty(----S)
D [13/Nov/2017:16:31:57 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [13/Nov/2017:16:31:57 +0100] [Client 5] Returning IPP successful-ok for Create-Printer-Subscriptions (/) from localhost
D [13/Nov/2017:16:31:57 +0100] [Client 5] Content-Length: 107
D [13/Nov/2017:16:31:57 +0100] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [13/Nov/2017:16:31:57 +0100] [Client 5] con->http=0x55efabf482b0
D [13/Nov/2017:16:31:57 +0100] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=107, response=0x55efabe82500(IPP_STATE_DATA), pipe_pid=0, file=-1
D [13/Nov/2017:16:31:57 +0100] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [13/Nov/2017:16:31:57 +0100] [Client 5] bytes=0, http_state=0, data_remaining=107
D [13/Nov/2017:16:31:57 +0100] [Client 5] Flushing write buffer.
D [13/Nov/2017:16:31:57 +0100] [Client 5] New state is HTTP_STATE_WAITING
D [13/Nov/2017:16:31:57 +0100] [Client 5] Waiting for request.
D [13/Nov/2017:16:31:57 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [13/Nov/2017:16:31:58 +0100] Expiring subscriptions...
D [13/Nov/2017:16:32:14 +0100] [Client 6] Accepted from localhost (Domain)
D [13/Nov/2017:16:32:14 +0100] [Client 6] Waiting for request.
I [13/Nov/2017:16:32:14 +0100] Expiring subscriptions...
D [13/Nov/2017:16:32:14 +0100] [Client 7] Accepted from localhost (Domain)
D [13/Nov/2017:16:32:14 +0100] [Client 7] Waiting for request.
D [13/Nov/2017:16:32:14 +0100] [Client 7] POST / HTTP/1.1
D [13/Nov/2017:16:32:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [13/Nov/2017:16:32:14 +0100] [Client 7] Read: status=200
D [13/Nov/2017:16:32:14 +0100] [Client 7] No authentication data provided.
D [13/Nov/2017:16:32:14 +0100] [Client 7] Read: status=100
D [13/Nov/2017:16:32:14 +0100] [Client 7] Read: status=100
D [13/Nov/2017:16:32:14 +0100] [Client 7] Read: status=100
D [13/Nov/2017:16:32:14 +0100] [Client 7] Read: status=100
D [13/Nov/2017:16:32:14 +0100] [Client 7] Read: status=100
D [13/Nov/2017:16:32:14 +0100] [Client 7] Read: status=100
D [13/Nov/2017:16:32:14 +0100] [Client 7] 2.0 Get-Job-Attributes 1
D [13/Nov/2017:16:32:14 +0100] Get-Job-Attributes ipp://localhost/jobs/41
D [13/Nov/2017:16:32:14 +0100] [Client 7] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/41) from localhost
D [13/Nov/2017:16:32:14 +0100] [Client 7] Content-Length: 75
D [13/Nov/2017:16:32:14 +0100] [Client 7] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [13/Nov/2017:16:32:14 +0100] [Client 7] con->http=0x55efabf54a40
D [13/Nov/2017:16:32:14 +0100] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55efabe824d0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [13/Nov/2017:16:32:14 +0100] [Client 7] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [13/Nov/2017:16:32:14 +0100] [Client 7] bytes=0, http_state=0, data_remaining=75
D [13/Nov/2017:16:32:14 +0100] [Client 7] Flushing write buffer.
D [13/Nov/2017:16:32:14 +0100] [Client 7] New state is HTTP_STATE_WAITING
D [13/Nov/2017:16:32:14 +0100] [Client 7] Waiting for request.
D [13/Nov/2017:16:32:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [13/Nov/2017:16:32:14 +0100] [Client 6] POST /jobs/ HTTP/1.1
D [13/Nov/2017:16:32:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [13/Nov/2017:16:32:14 +0100] [Client 6] Read: status=200
D [13/Nov/2017:16:32:14 +0100] [Client 6] No authentication data provided.
D [13/Nov/2017:16:32:14 +0100] [Client 6] 2.0 Cancel-Job 35
D [13/Nov/2017:16:32:14 +0100] Cancel-Job ipp://localhost/jobs/41
D [13/Nov/2017:16:32:14 +0100] cupsdIsAuthorized: requesting-user-name="tata"
D [13/Nov/2017:16:32:14 +0100] [Job 41] time-at-completed=1510587134
D [13/Nov/2017:16:32:14 +0100] Notifier dbus started - PID = 2968
D [13/Nov/2017:16:32:14 +0100] cupsdMarkDirty(----S)
D [13/Nov/2017:16:32:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [13/Nov/2017:16:32:14 +0100] [Job 41] Job canceled by \"tata\"
I [13/Nov/2017:16:32:14 +0100] Expiring subscriptions...
D [13/Nov/2017:16:32:14 +0100] cupsdMarkDirty(---J-)
D [13/Nov/2017:16:32:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [13/Nov/2017:16:32:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [13/Nov/2017:16:32:14 +0100] [Job 41] Canceled by "tata".
D [13/Nov/2017:16:32:14 +0100] [Client 6] Returning IPP successful-ok for Cancel-Job (ipp://localhost/jobs/41) from localhost
D [13/Nov/2017:16:32:14 +0100] [Client 6] Content-Length: 75
D [13/Nov/2017:16:32:14 +0100] [Client 6] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [13/Nov/2017:16:32:14 +0100] [Job 41] Removing document files.
D [13/Nov/2017:16:32:14 +0100] cupsdMarkDirty(---J-)
D [13/Nov/2017:16:32:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [13/Nov/2017:16:32:14 +0100] [Client 6] con->http=0x55efabf50670
D [13/Nov/2017:16:32:14 +0100] [Client 6] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55efabf4c790(IPP_STATE_DATA), pipe_pid=0, file=-1
D [13/Nov/2017:16:32:14 +0100] [Client 6] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [13/Nov/2017:16:32:14 +0100] [Client 6] bytes=0, http_state=0, data_remaining=75
D [13/Nov/2017:16:32:14 +0100] [Client 6] Flushing write buffer.
D [13/Nov/2017:16:32:14 +0100] [Client 6] New state is HTTP_STATE_WAITING
D [13/Nov/2017:16:32:14 +0100] [Client 6] Waiting for request.
D [13/Nov/2017:16:32:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [13/Nov/2017:16:32:14 +0100] [Notifier] state=3
D [13/Nov/2017:16:32:14 +0100] [Client 6] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [13/Nov/2017:16:32:14 +0100] [Client 6] Closing connection.
D [13/Nov/2017:16:32:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [13/Nov/2017:16:32:14 +0100] [Notifier] Connected to D-BUS
D [13/Nov/2017:16:32:14 +0100] [Notifier] JobCompleted
D [13/Nov/2017:16:32:15 +0100] [Client 8] Accepted from localhost (Domain)
D [13/Nov/2017:16:32:15 +0100] [Client 8] Waiting for request.
I [13/Nov/2017:16:32:15 +0100] Expiring subscriptions...
D [13/Nov/2017:16:32:15 +0100] [Client 8] POST / HTTP/1.1
D [13/Nov/2017:16:32:15 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [13/Nov/2017:16:32:15 +0100] [Client 8] Read: status=200
D [13/Nov/2017:16:32:15 +0100] [Client 8] No authentication data provided.
D [13/Nov/2017:16:32:15 +0100] [Client 8] 2.0 Get-Notifications 36
D [13/Nov/2017:16:32:15 +0100] Get-Notifications /
D [13/Nov/2017:16:32:15 +0100] cupsdIsAuthorized: requesting-user-name="tata"
D [13/Nov/2017:16:32:15 +0100] [Client 8] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [13/Nov/2017:16:32:15 +0100] [Client 8] Content-Length: 690
D [13/Nov/2017:16:32:15 +0100] [Client 8] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [13/Nov/2017:16:32:15 +0100] [Client 8] con->http=0x55efabf50670
D [13/Nov/2017:16:32:15 +0100] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=690, response=0x55efabf58670(IPP_STATE_DATA), pipe_pid=0, file=-1
D [13/Nov/2017:16:32:15 +0100] [Client 8] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [13/Nov/2017:16:32:15 +0100] [Client 8] bytes=0, http_state=0, data_remaining=690
D [13/Nov/2017:16:32:15 +0100] [Client 8] Flushing write buffer.
D [13/Nov/2017:16:32:15 +0100] [Client 8] New state is HTTP_STATE_WAITING
D [13/Nov/2017:16:32:15 +0100] [Client 8] Waiting for request.
D [13/Nov/2017:16:32:15 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [13/Nov/2017:16:32:15 +0100] [Client 8] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [13/Nov/2017:16:32:15 +0100] [Client 8] Closing connection.
D [13/Nov/2017:16:32:15 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [13/Nov/2017:16:32:16 +0100] Expiring subscriptions...
D [13/Nov/2017:16:32:18 +0100] [Client 9] Accepted from localhost (Domain)
D [13/Nov/2017:16:32:18 +0100] [Client 9] Waiting for request.
I [13/Nov/2017:16:32:18 +0100] Expiring subscriptions...
D [13/Nov/2017:16:32:18 +0100] [Client 9] POST / HTTP/1.1
D [13/Nov/2017:16:32:18 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [13/Nov/2017:16:32:18 +0100] [Client 9] Read: status=200
D [13/Nov/2017:16:32:18 +0100] [Client 9] No authentication data provided.
D [13/Nov/2017:16:32:18 +0100] [Client 9] Read: status=100
D [13/Nov/2017:16:32:18 +0100] [Client 9] Read: status=100
D [13/Nov/2017:16:32:18 +0100] [Client 9] Read: status=100
D [13/Nov/2017:16:32:18 +0100] [Client 9] Read: status=100
D [13/Nov/2017:16:32:18 +0100] [Client 9] Read: status=100
D [13/Nov/2017:16:32:18 +0100] [Client 9] Read: status=100
D [13/Nov/2017:16:32:18 +0100] [Client 9] Read: status=100
D [13/Nov/2017:16:32:18 +0100] [Client 9] 2.0 Cancel-Subscription 48
D [13/Nov/2017:16:32:18 +0100] Cancel-Subscription /
D [13/Nov/2017:16:32:18 +0100] cupsdIsAuthorized: requesting-user-name="tata"
D [13/Nov/2017:16:32:18 +0100] cupsdMarkDirty(----S)
D [13/Nov/2017:16:32:18 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [13/Nov/2017:16:32:18 +0100] [Client 9] Returning IPP successful-ok for Cancel-Subscription (/) from localhost
D [13/Nov/2017:16:32:18 +0100] [Client 9] Content-Length: 75
D [13/Nov/2017:16:32:18 +0100] [Client 9] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [13/Nov/2017:16:32:18 +0100] [Client 9] con->http=0x55efabf50670
D [13/Nov/2017:16:32:18 +0100] [Client 9] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55efabe824d0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [13/Nov/2017:16:32:18 +0100] [Client 9] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [13/Nov/2017:16:32:18 +0100] [Client 9] bytes=0, http_state=0, data_remaining=75
D [13/Nov/2017:16:32:18 +0100] [Client 9] Flushing write buffer.
D [13/Nov/2017:16:32:18 +0100] [Client 9] New state is HTTP_STATE_WAITING
D [13/Nov/2017:16:32:18 +0100] [Client 9] Waiting for request.
D [13/Nov/2017:16:32:18 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [13/Nov/2017:16:32:18 +0100] [Client 9] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [13/Nov/2017:16:32:18 +0100] [Client 9] Closing connection.
D [13/Nov/2017:16:32:18 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [13/Nov/2017:16:32:19 +0100] Expiring subscriptions...
I [13/Nov/2017:16:32:28 +0100] Saving job.cache...
I [13/Nov/2017:16:32:28 +0100] Saving subscriptions.conf...
D [13/Nov/2017:16:32:28 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
I [13/Nov/2017:16:32:28 +0100] Expiring subscriptions...
D [13/Nov/2017:16:32:45 +0100] [Client 7] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [13/Nov/2017:16:32:45 +0100] [Client 7] Closing connection.
D [13/Nov/2017:16:32:45 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [13/Nov/2017:16:32:45 +0100] Expiring subscriptions...
I [13/Nov/2017:16:32:46 +0100] Expiring subscriptions...
I [13/Nov/2017:16:36:58 +0100] Expiring subscriptions...
D [13/Nov/2017:16:36:58 +0100] [Job 41] Unloading...
D [13/Nov/2017:16:36:58 +0100] cupsdAddCert: Adding certificate for PID 0
D [13/Nov/2017:16:36:58 +0100] Closing client 1 after 300 seconds of inactivity.
D [13/Nov/2017:16:36:58 +0100] [Client 1] Closing connection.
D [13/Nov/2017:16:36:58 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [13/Nov/2017:16:36:58 +0100] Closing client 5 after 300 seconds of inactivity.
D [13/Nov/2017:16:36:58 +0100] [Client 5] Closing connection.
D [13/Nov/2017:16:36:58 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [13/Nov/2017:16:36:58 +0100] Report: clients=0
D [13/Nov/2017:16:36:58 +0100] Report: jobs=2
D [13/Nov/2017:16:36:58 +0100] Report: jobs-active=0
D [13/Nov/2017:16:36:58 +0100] Report: printers=1
D [13/Nov/2017:16:36:58 +0100] Report: stringpool-string-count=8816
D [13/Nov/2017:16:36:58 +0100] Report: stringpool-alloc-bytes=10984
D [13/Nov/2017:16:36:58 +0100] Report: stringpool-total-bytes=168088
D [13/Nov/2017:16:50:53 +0100] [Client 10] Accepted from localhost:51212 (IPv4)
D [13/Nov/2017:16:50:53 +0100] [Client 10] Waiting for request.
I [13/Nov/2017:16:50:53 +0100] Expiring subscriptions...
D [13/Nov/2017:16:50:53 +0100] cupsdAddCert: Adding certificate for PID 0
D [13/Nov/2017:16:50:53 +0100] Report: clients=1
D [13/Nov/2017:16:50:53 +0100] Report: jobs=2
D [13/Nov/2017:16:50:53 +0100] Report: jobs-active=0
D [13/Nov/2017:16:50:53 +0100] Report: printers=1
D [13/Nov/2017:16:50:53 +0100] Report: stringpool-string-count=8816
D [13/Nov/2017:16:50:53 +0100] Report: stringpool-alloc-bytes=10984
D [13/Nov/2017:16:50:53 +0100] Report: stringpool-total-bytes=168088
I [13/Nov/2017:16:50:54 +0100] Expiring subscriptions...
D [13/Nov/2017:16:50:58 +0100] [Client 10] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [13/Nov/2017:16:50:58 +0100] [Client 10] Closing connection.
D [13/Nov/2017:16:50:58 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [13/Nov/2017:16:50:58 +0100] Expiring subscriptions...
D [13/Nov/2017:16:50:58 +0100] [Client 11] Accepted from localhost:51214 (IPv4)
D [13/Nov/2017:16:50:58 +0100] [Client 11] Waiting for request.
I [13/Nov/2017:16:50:59 +0100] Expiring subscriptions...
D [13/Nov/2017:16:51:03 +0100] [Client 11] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [13/Nov/2017:16:51:03 +0100] [Client 11] Closing connection.
D [13/Nov/2017:16:51:03 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [13/Nov/2017:16:51:03 +0100] Expiring subscriptions...
I [13/Nov/2017:16:51:04 +0100] Expiring subscriptions...
D [13/Nov/2017:16:51:07 +0100] [Client 12] Accepted from localhost:51216 (IPv4)
D [13/Nov/2017:16:51:07 +0100] [Client 12] Waiting for request.
I [13/Nov/2017:16:51:07 +0100] Expiring subscriptions...
I [13/Nov/2017:16:51:08 +0100] Expiring subscriptions...
D [13/Nov/2017:16:51:12 +0100] [Client 12] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [13/Nov/2017:16:51:12 +0100] [Client 12] Closing connection.
D [13/Nov/2017:16:51:12 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [13/Nov/2017:16:51:12 +0100] Expiring subscriptions...
I [13/Nov/2017:16:51:13 +0100] Expiring subscriptions...
D [13/Nov/2017:16:51:15 +0100] [Client 13] Accepted from localhost:51218 (IPv4)
D [13/Nov/2017:16:51:15 +0100] [Client 13] Waiting for request.
I [13/Nov/2017:16:51:15 +0100] Expiring subscriptions...
I [13/Nov/2017:16:51:16 +0100] Expiring subscriptions...
D [13/Nov/2017:16:51:18 +0100] [Client 13] GET /admin HTTP/1.1
D [13/Nov/2017:16:51:18 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [13/Nov/2017:16:51:18 +0100] [Client 13] Read: status=200
D [13/Nov/2017:16:51:18 +0100] [Client 13] No authentication data provided.
D [13/Nov/2017:16:51:18 +0100] [Client 13] Processing GET /admin
D [13/Nov/2017:16:51:18 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@Tata-Mint"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/2.1.3"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[13] = "USER=root"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[15] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[17] = "IPP_PORT=631"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[18] = "LANG=pl.UTF8"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[21] = "SERVER_NAME=localhost"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[22] = "SERVER_PORT=631"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[23] = "REMOTE_ADDR=127.0.0.1"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[24] = "REMOTE_HOST=localhost"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[25] = "SCRIPT_NAME=/admin"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/admin"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[28] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:56.0) Gecko/20100101 Firefox/56.0"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[29] = "HTTP_REFERER=http://localhost:631/"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[30] = "REQUEST_METHOD=GET"
D [13/Nov/2017:16:51:18 +0100] [CGI] envp[31] = "QUERY_STRING="
D [13/Nov/2017:16:51:18 +0100] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi (PID 3798)
I [13/Nov/2017:16:51:18 +0100] [Client 13] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=3798, file=18)
D [13/Nov/2017:16:51:18 +0100] [Client 13] Waiting for CGI data.
I [13/Nov/2017:16:51:18 +0100] Expiring subscriptions...
D [13/Nov/2017:16:51:18 +0100] [CGI] admin.cgi started...
D [13/Nov/2017:16:51:18 +0100] [Client 14] Accepted from localhost (Domain)
D [13/Nov/2017:16:51:18 +0100] [Client 14] Waiting for request.
D [13/Nov/2017:16:51:18 +0100] [CGI] http=0x55d4a9f03b70
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: SECTION=\"admin\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
D [13/Nov/2017:16:51:18 +0100] [CGI] org.cups.sid cookie not found, initializing!
D [13/Nov/2017:16:51:18 +0100] [CGI] org.cups.sid cookie is \"cefd1dcd0502adafb3aecba5f4a8b008\"
D [13/Nov/2017:16:51:18 +0100] [CGI] No form data, showing main menu...
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: DEBUG_LOGGING=\"CHECKED\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: SHARE_PRINTERS=\"CHECKED\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: HAVE_GSSAPI=\"1\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: KERBEROS=\"\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: PRESERVE_JOBS=\"CHECKED\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: PRESERVE_JOB_HISTORY=\"Yes\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: PRESERVE_JOB_FILES=\"1d\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: MAX_CLIENTS=\"100\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: MAX_JOBS=\"500\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: MAX_LOG_SIZE=\"0\"
D [13/Nov/2017:16:51:18 +0100] [CGI] /usr/share/cups/drivers/pscript5.dll: No such file or directory
D [13/Nov/2017:16:51:18 +0100] [Client 14] POST / HTTP/1.1
D [13/Nov/2017:16:51:18 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [13/Nov/2017:16:51:18 +0100] [Client 14] Read: status=200
D [13/Nov/2017:16:51:18 +0100] [Client 14] No authentication data provided.
D [13/Nov/2017:16:51:18 +0100] [Client 14] 2.0 Get-Subscriptions 1
D [13/Nov/2017:16:51:18 +0100] Get-Subscriptions ipp://localhost/
I [13/Nov/2017:16:51:18 +0100] Expiring subscriptions...
D [13/Nov/2017:16:51:18 +0100] [Client 14] Returning IPP successful-ok for Get-Subscriptions (ipp://localhost/) from localhost
D [13/Nov/2017:16:51:18 +0100] [Client 14] Content-Length: 530
D [13/Nov/2017:16:51:18 +0100] [Client 14] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [13/Nov/2017:16:51:18 +0100] [Client 14] con->http=0x55efabf54a40
D [13/Nov/2017:16:51:18 +0100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=530, response=0x55efabf3ece0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [13/Nov/2017:16:51:18 +0100] [Client 14] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [13/Nov/2017:16:51:18 +0100] [Client 14] bytes=0, http_state=0, data_remaining=530
D [13/Nov/2017:16:51:18 +0100] [Client 14] Flushing write buffer.
D [13/Nov/2017:16:51:18 +0100] [Client 14] New state is HTTP_STATE_WAITING
D [13/Nov/2017:16:51:18 +0100] [Client 14] Waiting for request.
D [13/Nov/2017:16:51:18 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetArray: notify_lease_duration[0]=\"86400\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetArray: notify_time_interval[0]=\"0\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetArray: notify_subscription_id[0]=\"208\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetArray: notify_lease_duration[1]=\"86400\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetArray: notify_time_interval[1]=\"0\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetArray: notify_subscription_id[1]=\"219\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetArray: notify_lease_duration[2]=\"86400\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetArray: notify_time_interval[2]=\"60\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetArray: notify_subscription_id[2]=\"220\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetArray: notify_events[3]=\"all\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetArray: notify_lease_duration[3]=\"86400\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetArray: notify_recipient_uri[3]=\"dbus://\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetArray: notify_recipient_name[3]=\"/\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetArray: notify_subscriber_user_name[3]=\"anonymous\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetArray: notify_time_interval[3]=\"0\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetArray: notify_subscription_id[3]=\"221\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: TITLE=\"Administration\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
D [13/Nov/2017:16:51:18 +0100] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
D [13/Nov/2017:16:51:18 +0100] [Client 13] CGI data ready to be sent.
D [13/Nov/2017:16:51:18 +0100] [Client 13] con->http=0x55efabf50670
D [13/Nov/2017:16:51:18 +0100] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=3798, file=18
D [13/Nov/2017:16:51:18 +0100] [Client 13] Waiting for CGI data.
D [13/Nov/2017:16:51:18 +0100] [Client 13] Script header: Set-Cookie: org.cups.sid=cefd1dcd0502adafb3aecba5f4a8b008; path=/; httponly;
D [13/Nov/2017:16:51:18 +0100] [Client 13] Script header: Content-Type: text/html;charset=utf-8
D [13/Nov/2017:16:51:18 +0100] [Client 13] Script header:
D [13/Nov/2017:16:51:18 +0100] [Client 13] Sending status 200 for CGI.
D [13/Nov/2017:16:51:18 +0100] [Client 13] cupsdSendHeader: code=200, type="(null)", auth_type=0
D [13/Nov/2017:16:51:18 +0100] [Client 13] con->http=0x55efabf50670
D [13/Nov/2017:16:51:18 +0100] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=3798, file=18
D [13/Nov/2017:16:51:18 +0100] [Client 13] Waiting for CGI data.
D [13/Nov/2017:16:51:18 +0100] [Client 13] CGI data ready to be sent.
D [13/Nov/2017:16:51:18 +0100] [Client 13] con->http=0x55efabf50670
D [13/Nov/2017:16:51:18 +0100] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=3798, file=18
D [13/Nov/2017:16:51:18 +0100] [Client 13] Waiting for CGI data.
D [13/Nov/2017:16:51:18 +0100] [Client 13] con->http=0x55efabf50670
D [13/Nov/2017:16:51:18 +0100] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=3798, file=18
D [13/Nov/2017:16:51:18 +0100] [Client 13] Waiting for CGI data.
D [13/Nov/2017:16:51:18 +0100] [Client 13] CGI data ready to be sent.
D [13/Nov/2017:16:51:18 +0100] [Client 13] con->http=0x55efabf50670
D [13/Nov/2017:16:51:18 +0100] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=3798, file=18
D [13/Nov/2017:16:51:18 +0100] [Client 13] Waiting for CGI data.
D [13/Nov/2017:16:51:18 +0100] PID 3798 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [13/Nov/2017:16:51:18 +0100] [Client 13] con->http=0x55efabf50670
D [13/Nov/2017:16:51:18 +0100] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=3798, file=18
D [13/Nov/2017:16:51:18 +0100] [Client 13] Waiting for CGI data.
D [13/Nov/2017:16:51:18 +0100] [Client 13] CGI data ready to be sent.
D [13/Nov/2017:16:51:18 +0100] [Client 14] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [13/Nov/2017:16:51:18 +0100] [Client 14] Closing connection.
D [13/Nov/2017:16:51:18 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [13/Nov/2017:16:51:18 +0100] [Client 13] con->http=0x55efabf50670
D [13/Nov/2017:16:51:18 +0100] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=3798, file=18
D [13/Nov/2017:16:51:18 +0100] [Client 13] Waiting for CGI data.
D [13/Nov/2017:16:51:18 +0100] [Client 13] con->http=0x55efabf50670
D [13/Nov/2017:16:51:18 +0100] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=3798, file=18
D [13/Nov/2017:16:51:18 +0100] [Client 13] Waiting for CGI data.
D [13/Nov/2017:16:51:18 +0100] [Client 13] CGI data ready to be sent.
D [13/Nov/2017:16:51:18 +0100] [Client 13] con->http=0x55efabf50670
D [13/Nov/2017:16:51:18 +0100] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=3798, file=18
D [13/Nov/2017:16:51:18 +0100] [Client 13] Waiting for CGI data.
D [13/Nov/2017:16:51:18 +0100] [Client 13] Sending 0-length chunk.
D [13/Nov/2017:16:51:18 +0100] [Client 13] Flushing write buffer.
D [13/Nov/2017:16:51:18 +0100] [Client 13] New state is HTTP_STATE_WAITING
D [13/Nov/2017:16:51:18 +0100] [Client 13] Waiting for request.
D [13/Nov/2017:16:51:18 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
I [13/Nov/2017:16:51:19 +0100] Expiring subscriptions...
D [13/Nov/2017:16:51:29 +0100] [Client 13] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [13/Nov/2017:16:51:29 +0100] [Client 13] Closing connection.
D [13/Nov/2017:16:51:29 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [13/Nov/2017:16:51:29 +0100] Expiring subscriptions...
I [13/Nov/2017:16:51:30 +0100] Expiring subscriptions...
D [13/Nov/2017:16:51:33 +0100] [Client 15] Accepted from localhost:51220 (IPv4)
D [13/Nov/2017:16:51:33 +0100] [Client 15] Waiting for request.
I [13/Nov/2017:16:51:33 +0100] Expiring subscriptions...
I [13/Nov/2017:16:51:34 +0100] Expiring subscriptions...
D [13/Nov/2017:16:51:38 +0100] [Client 15] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [13/Nov/2017:16:51:38 +0100] [Client 15] Closing connection.
D [13/Nov/2017:16:51:38 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [13/Nov/2017:16:51:38 +0100] Expiring subscriptions...
I [13/Nov/2017:16:51:39 +0100] Expiring subscriptions...
D [13/Nov/2017:16:51:47 +0100] [Client 16] Accepted from localhost:51226 (IPv4)
D [13/Nov/2017:16:51:47 +0100] [Client 16] Waiting for request.
I [13/Nov/2017:16:51:47 +0100] Expiring subscriptions...
I [13/Nov/2017:16:51:48 +0100] Expiring subscriptions...
D [13/Nov/2017:16:51:53 +0100] [Client 16] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [13/Nov/2017:16:51:53 +0100] [Client 16] Closing connection.
D [13/Nov/2017:16:51:53 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [13/Nov/2017:16:51:53 +0100] Expiring subscriptions...
D [13/Nov/2017:16:51:53 +0100] Report: clients=0
D [13/Nov/2017:16:51:53 +0100] Report: jobs=2
D [13/Nov/2017:16:51:53 +0100] Report: jobs-active=0
D [13/Nov/2017:16:51:53 +0100] Report: printers=1
D [13/Nov/2017:16:51:53 +0100] Report: stringpool-string-count=8816
D [13/Nov/2017:16:51:53 +0100] Report: stringpool-alloc-bytes=10984
D [13/Nov/2017:16:51:53 +0100] Report: stringpool-total-bytes=168088
I [13/Nov/2017:16:51:54 +0100] Expiring subscriptions...
D [13/Nov/2017:16:51:59 +0100] [Client 17] Accepted from localhost:51228 (IPv4)
D [13/Nov/2017:16:51:59 +0100] [Client 17] Waiting for request.
I [13/Nov/2017:16:51:59 +0100] Expiring subscriptions...
D [13/Nov/2017:16:51:59 +0100] [Client 17] GET /admin/log/error_log HTTP/1.1
D [13/Nov/2017:16:51:59 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [13/Nov/2017:16:51:59 +0100] [Client 17] Read: status=200
D [13/Nov/2017:16:51:59 +0100] [Client 17] No authentication data provided.
D [13/Nov/2017:16:51:59 +0100] cupsdIsAuthorized: username=""
D [13/Nov/2017:16:51:59 +0100] [Client 17] cupsdSendHeader: code=401, type="text/html", auth_type=0
D [13/Nov/2017:16:51:59 +0100] [Client 17] WWW-Authenticate: Basic realm=\"CUPS\", trc=\"y\"
D [13/Nov/2017:16:51:59 +0100] [Client 17] Closing connection.
D [13/Nov/2017:16:51:59 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
I [13/Nov/2017:16:52:00 +0100] Expiring subscriptions...
D [13/Nov/2017:16:52:01 +0100] [Client 18] Accepted from localhost:51230 (IPv4)
D [13/Nov/2017:16:52:01 +0100] [Client 18] Waiting for request.
I [13/Nov/2017:16:52:01 +0100] Expiring subscriptions...
D [13/Nov/2017:16:52:01 +0100] [Client 18] GET /admin/log/error_log HTTP/1.1
D [13/Nov/2017:16:52:01 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [13/Nov/2017:16:52:01 +0100] [Client 18] Read: status=200
D [13/Nov/2017:16:52:01 +0100] [Client 18] Authorized as "tata" using Basic.
D [13/Nov/2017:16:52:01 +0100] cupsdIsAuthorized: username="tata"
D [13/Nov/2017:16:52:01 +0100] [Client 18] Processing GET /admin/log/error_log
Nie jestem pewien czy wszystko jest tak jak chciałeś, bedę gotowy w każdej chwili aby coś poprawić albo dodać.