nächtes Ergebnis > 'tail -n 150 /var/log/cups/error_log' – ist ja noch schlimmer...
D [25/Dec/2018:14:43:46 +0100] Report: printers=1
D [25/Dec/2018:14:43:46 +0100] Report: stringpool-string-count=17357
D [25/Dec/2018:14:43:46 +0100] Report: stringpool-alloc-bytes=10728
D [25/Dec/2018:14:43:46 +0100] Report: stringpool-total-bytes=347288
D [25/Dec/2018:14:43:46 +0100] [Client 429] POST / HTTP/1.1
D [25/Dec/2018:14:43:46 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [25/Dec/2018:14:43:46 +0100] [Client 429] Read: status=200, state=6
D [25/Dec/2018:14:43:46 +0100] [Client 429] No authentication data provided.
D [25/Dec/2018:14:43:46 +0100] [Client 429] 2.0 Get-Notifications 23
D [25/Dec/2018:14:43:46 +0100] Get-Notifications /
D [25/Dec/2018:14:43:46 +0100] cupsdIsAuthorized: requesting-user-name="xy"
D [25/Dec/2018:14:43:46 +0100] [Client 429] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [25/Dec/2018:14:43:46 +0100] [Client 429] Content-Length: 127
D [25/Dec/2018:14:43:46 +0100] [Client 429] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Dec/2018:14:43:46 +0100] [Client 429] con->http=0x55d3b4306b60
D [25/Dec/2018:14:43:46 +0100] [Client 429] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x55d3b4326ba0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Dec/2018:14:43:46 +0100] [Client 429] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Dec/2018:14:43:46 +0100] [Client 429] bytes=0, http_state=0, data_remaining=127
D [25/Dec/2018:14:43:46 +0100] [Client 429] Flushing write buffer.
D [25/Dec/2018:14:43:46 +0100] [Client 429] New state is HTTP_STATE_WAITING
D [25/Dec/2018:14:43:46 +0100] [Client 429] Waiting for request.
D [25/Dec/2018:14:43:46 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [25/Dec/2018:14:43:46 +0100] [Client 429] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Dec/2018:14:43:46 +0100] [Client 429] Closing connection.
D [25/Dec/2018:14:43:46 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [25/Dec/2018:14:43:47 +0100] Expiring subscriptions...
D [25/Dec/2018:14:44:46 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [25/Dec/2018:14:44:46 +0100] [Client 430] Server address is "/run/cups/cups.sock".
D [25/Dec/2018:14:44:46 +0100] [Client 430] Accepted from localhost (Domain)
D [25/Dec/2018:14:44:46 +0100] [Client 430] Waiting for request.
I [25/Dec/2018:14:44:46 +0100] Expiring subscriptions...
D [25/Dec/2018:14:44:46 +0100] Report: clients=1
D [25/Dec/2018:14:44:46 +0100] Report: jobs=37
D [25/Dec/2018:14:44:46 +0100] Report: jobs-active=0
D [25/Dec/2018:14:44:46 +0100] Report: printers=1
D [25/Dec/2018:14:44:46 +0100] Report: stringpool-string-count=17357
D [25/Dec/2018:14:44:46 +0100] Report: stringpool-alloc-bytes=10728
D [25/Dec/2018:14:44:46 +0100] Report: stringpool-total-bytes=347288
D [25/Dec/2018:14:44:46 +0100] [Client 430] POST / HTTP/1.1
D [25/Dec/2018:14:44:46 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [25/Dec/2018:14:44:46 +0100] [Client 430] Read: status=200, state=6
D [25/Dec/2018:14:44:46 +0100] [Client 430] No authentication data provided.
D [25/Dec/2018:14:44:46 +0100] [Client 430] 2.0 Get-Notifications 24
D [25/Dec/2018:14:44:46 +0100] Get-Notifications /
D [25/Dec/2018:14:44:46 +0100] cupsdIsAuthorized: requesting-user-name="xy"
D [25/Dec/2018:14:44:46 +0100] [Client 430] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [25/Dec/2018:14:44:46 +0100] [Client 430] Content-Length: 127
D [25/Dec/2018:14:44:46 +0100] [Client 430] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Dec/2018:14:44:46 +0100] [Client 430] con->http=0x55d3b4306b60
D [25/Dec/2018:14:44:46 +0100] [Client 430] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x55d3b42c83e0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Dec/2018:14:44:46 +0100] [Client 430] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Dec/2018:14:44:46 +0100] [Client 430] bytes=0, http_state=0, data_remaining=127
D [25/Dec/2018:14:44:46 +0100] [Client 430] Flushing write buffer.
D [25/Dec/2018:14:44:46 +0100] [Client 430] New state is HTTP_STATE_WAITING
D [25/Dec/2018:14:44:46 +0100] [Client 430] Waiting for request.
D [25/Dec/2018:14:44:46 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [25/Dec/2018:14:44:46 +0100] [Client 430] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Dec/2018:14:44:46 +0100] [Client 430] Closing connection.
D [25/Dec/2018:14:44:46 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [25/Dec/2018:14:44:47 +0100] Expiring subscriptions...
D [25/Dec/2018:14:45:44 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [25/Dec/2018:14:45:44 +0100] [Client 431] Server address is "/run/cups/cups.sock".
D [25/Dec/2018:14:45:44 +0100] [Client 431] Accepted from localhost (Domain)
D [25/Dec/2018:14:45:44 +0100] [Client 431] Waiting for request.
I [25/Dec/2018:14:45:44 +0100] Expiring subscriptions...
D [25/Dec/2018:14:45:44 +0100] [Client 431] POST / HTTP/1.1
D [25/Dec/2018:14:45:44 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [25/Dec/2018:14:45:44 +0100] [Client 431] Read: status=200, state=6
D [25/Dec/2018:14:45:44 +0100] [Client 431] No authentication data provided.
D [25/Dec/2018:14:45:44 +0100] [Client 431] 2.0 Cancel-Subscription 25
D [25/Dec/2018:14:45:44 +0100] Cancel-Subscription /
D [25/Dec/2018:14:45:44 +0100] cupsdIsAuthorized: requesting-user-name="xy"
D [25/Dec/2018:14:45:44 +0100] cupsdMarkDirty(----S)
D [25/Dec/2018:14:45:44 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [25/Dec/2018:14:45:44 +0100] [Client 431] Returning IPP successful-ok for Cancel-Subscription (/) from localhost
D [25/Dec/2018:14:45:44 +0100] [Client 431] Content-Length: 75
D [25/Dec/2018:14:45:44 +0100] [Client 431] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Dec/2018:14:45:44 +0100] [Client 431] con->http=0x55d3b4306b60
D [25/Dec/2018:14:45:44 +0100] [Client 431] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55d3b42006f0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Dec/2018:14:45:44 +0100] [Client 431] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Dec/2018:14:45:44 +0100] [Client 431] bytes=0, http_state=0, data_remaining=75
D [25/Dec/2018:14:45:44 +0100] [Client 431] Flushing write buffer.
D [25/Dec/2018:14:45:44 +0100] [Client 431] New state is HTTP_STATE_WAITING
D [25/Dec/2018:14:45:44 +0100] [Client 431] Waiting for request.
D [25/Dec/2018:14:45:44 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Dec/2018:14:45:44 +0100] [Client 431] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Dec/2018:14:45:44 +0100] [Client 431] Closing connection.
D [25/Dec/2018:14:45:44 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [25/Dec/2018:14:45:45 +0100] Expiring subscriptions...
I [25/Dec/2018:14:46:15 +0100] Saving subscriptions.conf...
D [25/Dec/2018:14:46:15 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
I [25/Dec/2018:14:46:15 +0100] Expiring subscriptions...
D [25/Dec/2018:14:46:15 +0100] Report: clients=0
D [25/Dec/2018:14:46:15 +0100] Report: jobs=37
D [25/Dec/2018:14:46:15 +0100] Report: jobs-active=0
D [25/Dec/2018:14:46:15 +0100] Report: printers=1
D [25/Dec/2018:14:46:15 +0100] Report: stringpool-string-count=17357
D [25/Dec/2018:14:46:15 +0100] Report: stringpool-alloc-bytes=10728
D [25/Dec/2018:14:46:15 +0100] Report: stringpool-total-bytes=347288
D [25/Dec/2018:14:52:25 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [25/Dec/2018:14:52:25 +0100] [Client 432] Server address is "/run/cups/cups.sock".
D [25/Dec/2018:14:52:25 +0100] [Client 432] Accepted from localhost (Domain)
D [25/Dec/2018:14:52:25 +0100] [Client 432] Waiting for request.
I [25/Dec/2018:14:52:25 +0100] Expiring subscriptions...
D [25/Dec/2018:14:52:25 +0100] cupsdAddCert: Adding certificate for PID 0
D [25/Dec/2018:14:52:25 +0100] Report: clients=1
D [25/Dec/2018:14:52:25 +0100] Report: jobs=37
D [25/Dec/2018:14:52:25 +0100] Report: jobs-active=0
D [25/Dec/2018:14:52:25 +0100] Report: printers=1
D [25/Dec/2018:14:52:25 +0100] Report: stringpool-string-count=17357
D [25/Dec/2018:14:52:25 +0100] Report: stringpool-alloc-bytes=10728
D [25/Dec/2018:14:52:25 +0100] Report: stringpool-total-bytes=347288
D [25/Dec/2018:14:52:25 +0100] [Client 432] POST / HTTP/1.1
D [25/Dec/2018:14:52:25 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [25/Dec/2018:14:52:25 +0100] [Client 432] Read: status=200, state=6
D [25/Dec/2018:14:52:25 +0100] [Client 432] No authentication data provided.
D [25/Dec/2018:14:52:25 +0100] [Client 432] 2.0 CUPS-Get-Printers 1
D [25/Dec/2018:14:52:25 +0100] CUPS-Get-Printers
D [25/Dec/2018:14:52:25 +0100] [Client 432] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [25/Dec/2018:14:52:25 +0100] [Client 432] Content-Length: 9767
D [25/Dec/2018:14:52:25 +0100] [Client 432] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Dec/2018:14:52:25 +0100] [Client 432] con->http=0x55d3b4359170
D [25/Dec/2018:14:52:25 +0100] [Client 432] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9767, response=0x55d3b4333ce0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Dec/2018:14:52:25 +0100] [Client 432] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Dec/2018:14:52:25 +0100] [Client 432] bytes=0, http_state=0, data_remaining=9767
D [25/Dec/2018:14:52:25 +0100] [Client 432] Flushing write buffer.
D [25/Dec/2018:14:52:25 +0100] [Client 432] New state is HTTP_STATE_WAITING
D [25/Dec/2018:14:52:25 +0100] [Client 432] Waiting for request.
D [25/Dec/2018:14:52:25 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [25/Dec/2018:14:52:25 +0100] [Client 432] POST / HTTP/1.1
D [25/Dec/2018:14:52:25 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [25/Dec/2018:14:52:25 +0100] [Client 432] Read: status=200, state=6
D [25/Dec/2018:14:52:25 +0100] [Client 432] No authentication data provided.
D [25/Dec/2018:14:52:25 +0100] [Client 432] 2.0 CUPS-Get-Classes 1
D [25/Dec/2018:14:52:25 +0100] CUPS-Get-Classes
D [25/Dec/2018:14:52:25 +0100] [Client 432] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [25/Dec/2018:14:52:25 +0100] [Client 432] Content-Length: 75
D [25/Dec/2018:14:52:25 +0100] [Client 432] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Dec/2018:14:52:25 +0100] [Client 432] con->http=0x55d3b4359170
D [25/Dec/2018:14:52:25 +0100] [Client 432] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55d3b438b110(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Dec/2018:14:52:25 +0100] [Client 432] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Dec/2018:14:52:25 +0100] [Client 432] bytes=0, http_state=0, data_remaining=75
D [25/Dec/2018:14:52:25 +0100] [Client 432] Flushing write buffer.
D [25/Dec/2018:14:52:25 +0100] [Client 432] New state is HTTP_STATE_WAITING
D [25/Dec/2018:14:52:25 +0100] [Client 432] Waiting for request.
D [25/Dec/2018:14:52:25 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [25/Dec/2018:14:52:25 +0100] [Client 432] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Dec/2018:14:52:25 +0100] [Client 432] Closing connection.
D [25/Dec/2018:14:52:25 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [25/Dec/2018:14:52:26 +0100] Expiring subscriptions...