D [28/Jun/2018:12:49:05 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:49:05 +0200] [Client 1] bytes=0, http_state=0, data_remaining=484 D [28/Jun/2018:12:49:05 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:49:05 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:49:05 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:49:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [28/Jun/2018:12:49:05 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:49:05 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:49:05 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:49:05 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:49:05 +0200] [Client 1] 2.0 Get-Jobs 310 D [28/Jun/2018:12:49:05 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:49:05 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:49:05 +0200] [Client 1] Content-Length: 484 D [28/Jun/2018:12:49:05 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:49:05 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:49:05 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=484, response=0x560367711f90(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:49:05 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:49:05 +0200] [Client 1] bytes=0, http_state=0, data_remaining=484 D [28/Jun/2018:12:49:05 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:49:05 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:49:05 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:49:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [28/Jun/2018:12:49:05 +0200] [Job 3154] PID 24979 (/usr/lib/cups/filter/gstoraster) exited with no errors. D [28/Jun/2018:12:49:05 +0200] [Job 3154] PID 24980 (/usr/lib/cups/filter/hpcups) exited with no errors. D [28/Jun/2018:12:49:05 +0200] [Job 3154] Wrote 8192 bytes of print data... D [28/Jun/2018:12:49:05 +0200] [Job 3154] Read 8192 bytes of print data... D [28/Jun/2018:12:49:05 +0200] [Job 3154] Wrote 8192 bytes of print data... D [28/Jun/2018:12:49:05 +0200] [Job 3154] Read 8192 bytes of print data... D [28/Jun/2018:12:49:05 +0200] [Job 3154] Wrote 8192 bytes of print data... D [28/Jun/2018:12:49:05 +0200] [Job 3154] Read 8192 bytes of print data... D [28/Jun/2018:12:49:05 +0200] [Job 3154] Wrote 8192 bytes of print data... D [28/Jun/2018:12:49:05 +0200] [Job 3154] Read 3221 bytes of print data... D [28/Jun/2018:12:49:05 +0200] [Job 3154] Wrote 3221 bytes of print data... D [28/Jun/2018:12:49:05 +0200] [Job 3154] STATE: +cups-waiting-for-job-completed D [28/Jun/2018:12:49:05 +0200] cupsdMarkDirty(P----) D [28/Jun/2018:12:49:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:49:05 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:49:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:49:05 +0200] [Job 3154] Waiting for printer to finish. D [28/Jun/2018:12:49:05 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:49:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:49:05 +0200] [Job 3154] Set job-printer-state-message to "Waiting for printer to finish.", current level=INFO D [28/Jun/2018:12:49:05 +0200] cupsdMarkDirty(----S) D [28/Jun/2018:12:49:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:49:05 +0200] Discarding unused printer-state-changed event... D [28/Jun/2018:12:49:05 +0200] [Notifier] state=3 D [28/Jun/2018:12:49:05 +0200] [Notifier] JobProgress D [28/Jun/2018:12:49:05 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:49:05 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:49:05 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:49:05 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:49:05 +0200] [Client 1] 2.0 Get-Jobs 311 D [28/Jun/2018:12:49:05 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:49:05 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:49:05 +0200] [Client 1] Content-Length: 495 D [28/Jun/2018:12:49:05 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:49:05 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:49:05 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=495, response=0x560367713440(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:49:05 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:49:05 +0200] [Client 1] bytes=0, http_state=0, data_remaining=495 D [28/Jun/2018:12:49:05 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:49:05 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:49:05 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:49:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [28/Jun/2018:12:49:05 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:49:05 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:49:05 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:49:05 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:49:05 +0200] [Client 1] 2.0 Get-Jobs 312 D [28/Jun/2018:12:49:05 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:49:05 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:49:05 +0200] [Client 1] Content-Length: 495 D [28/Jun/2018:12:49:05 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:49:05 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:49:05 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=495, response=0x560367713330(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:49:05 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:49:05 +0200] [Client 1] bytes=0, http_state=0, data_remaining=495 D [28/Jun/2018:12:49:05 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:49:05 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:49:05 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:49:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" I [28/Jun/2018:12:49:06 +0200] Expiring subscriptions... I [28/Jun/2018:12:49:09 +0200] Expiring subscriptions... D [28/Jun/2018:12:49:09 +0200] [Job 3154] PID 24981 (/usr/lib/cups/backend/socket) exited with no errors. D [28/Jun/2018:12:49:09 +0200] cupsdMarkDirty(P----) D [28/Jun/2018:12:49:09 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:49:09 +0200] [Job 3154] time-at-completed=1530182949 D [28/Jun/2018:12:49:09 +0200] cupsdMarkDirty(----S) D [28/Jun/2018:12:49:09 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" I [28/Jun/2018:12:49:09 +0200] [Job 3154] Job completed. I [28/Jun/2018:12:49:09 +0200] Expiring subscriptions... D [28/Jun/2018:12:49:09 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:49:09 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:49:09 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:49:09 +0200] Discarding unused printer-state-changed event... D [28/Jun/2018:12:49:09 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:49:09 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:49:09 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:49:09 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:49:09 +0200] [Notifier] state=3 D [28/Jun/2018:12:49:09 +0200] [Notifier] JobCompleted D [28/Jun/2018:12:49:09 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:49:09 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:49:09 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:49:09 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:49:09 +0200] [Client 1] 2.0 Get-Jobs 313 D [28/Jun/2018:12:49:09 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:49:09 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:49:09 +0200] [Client 1] Content-Length: 75 D [28/Jun/2018:12:49:09 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:49:09 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:49:09 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x560367712870(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:49:09 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:49:09 +0200] [Client 1] bytes=0, http_state=0, data_remaining=75 D [28/Jun/2018:12:49:09 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:49:09 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:49:09 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:49:09 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [28/Jun/2018:12:49:09 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:49:09 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [28/Jun/2018:12:49:09 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:49:09 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:49:09 +0200] [Client 1] 2.0 Get-Jobs 314 D [28/Jun/2018:12:49:09 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:49:09 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:49:09 +0200] [Client 1] Content-Length: 75 D [28/Jun/2018:12:49:09 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:49:09 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:49:09 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x560367712900(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:49:09 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:49:09 +0200] [Client 1] bytes=0, http_state=0, data_remaining=75 D [28/Jun/2018:12:49:09 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:49:09 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:49:09 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:49:09 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" I [28/Jun/2018:12:49:10 +0200] Expiring subscriptions... D [28/Jun/2018:12:49:10 +0200] [Job 3154] Unloading... I [28/Jun/2018:12:49:25 +0200] Saving printers.conf... I [28/Jun/2018:12:49:25 +0200] Saving job.cache... I [28/Jun/2018:12:49:25 +0200] Saving subscriptions.conf... D [28/Jun/2018:12:49:25 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files" I [28/Jun/2018:12:49:25 +0200] Expiring subscriptions... D [28/Jun/2018:12:49:25 +0200] Report: clients=2 D [28/Jun/2018:12:49:25 +0200] Report: jobs=27 D [28/Jun/2018:12:49:25 +0200] Report: jobs-active=0 D [28/Jun/2018:12:49:25 +0200] Report: printers=2 D [28/Jun/2018:12:49:25 +0200] Report: stringpool-string-count=3648 D [28/Jun/2018:12:49:25 +0200] Report: stringpool-alloc-bytes=12128 D [28/Jun/2018:12:49:25 +0200] Report: stringpool-total-bytes=70416 D [28/Jun/2018:12:50:42 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [28/Jun/2018:12:50:42 +0200] [Client 11] Server address is "/run/cups/cups.sock". D [28/Jun/2018:12:50:42 +0200] [Client 11] Accepted from localhost (Domain) D [28/Jun/2018:12:50:42 +0200] [Client 11] Waiting for request. I [28/Jun/2018:12:50:42 +0200] Expiring subscriptions... D [28/Jun/2018:12:50:42 +0200] Report: clients=3 D [28/Jun/2018:12:50:42 +0200] Report: jobs=27 D [28/Jun/2018:12:50:42 +0200] Report: jobs-active=0 D [28/Jun/2018:12:50:42 +0200] Report: printers=2 D [28/Jun/2018:12:50:42 +0200] Report: stringpool-string-count=3648 D [28/Jun/2018:12:50:42 +0200] Report: stringpool-alloc-bytes=12128 D [28/Jun/2018:12:50:42 +0200] Report: stringpool-total-bytes=70416 D [28/Jun/2018:12:50:42 +0200] [Client 11] POST / HTTP/1.1 D [28/Jun/2018:12:50:42 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [28/Jun/2018:12:50:42 +0200] [Client 11] Read: status=200, state=6 D [28/Jun/2018:12:50:42 +0200] [Client 11] No authentication data provided. D [28/Jun/2018:12:50:42 +0200] [Client 11] 2.0 CUPS-Get-Printers 12 D [28/Jun/2018:12:50:42 +0200] CUPS-Get-Printers D [28/Jun/2018:12:50:42 +0200] [Client 11] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost. D [28/Jun/2018:12:50:42 +0200] [Client 11] Content-Length: 2490 D [28/Jun/2018:12:50:42 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:42 +0200] [Client 11] con->http=0x560367729080 D [28/Jun/2018:12:50:42 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2490, response=0x56036770f560(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:42 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:42 +0200] [Client 11] bytes=0, http_state=0, data_remaining=2490 D [28/Jun/2018:12:50:42 +0200] [Client 11] Flushing write buffer. D [28/Jun/2018:12:50:42 +0200] [Client 11] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:42 +0200] [Client 11] Waiting for request. D [28/Jun/2018:12:50:42 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [28/Jun/2018:12:50:42 +0200] [Client 11] POST / HTTP/1.1 D [28/Jun/2018:12:50:42 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [28/Jun/2018:12:50:42 +0200] [Client 11] Read: status=200, state=6 D [28/Jun/2018:12:50:42 +0200] [Client 11] No authentication data provided. D [28/Jun/2018:12:50:42 +0200] [Client 11] 2.0 CUPS-Get-Default 13 D [28/Jun/2018:12:50:42 +0200] CUPS-Get-Default D [28/Jun/2018:12:50:42 +0200] CUPS-Get-Default client-error-not-found: Aucune imprimante par défaut. D [28/Jun/2018:12:50:42 +0200] [Client 11] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost. D [28/Jun/2018:12:50:42 +0200] [Client 11] Content-Length: 124 D [28/Jun/2018:12:50:42 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:42 +0200] [Client 11] con->http=0x560367729080 D [28/Jun/2018:12:50:42 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=124, response=0x560367713490(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:42 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:42 +0200] [Client 11] bytes=0, http_state=0, data_remaining=124 D [28/Jun/2018:12:50:42 +0200] [Client 11] Flushing write buffer. D [28/Jun/2018:12:50:42 +0200] [Client 11] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:42 +0200] [Client 11] Waiting for request. D [28/Jun/2018:12:50:42 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [28/Jun/2018:12:50:42 +0200] [Client 11] POST / HTTP/1.1 D [28/Jun/2018:12:50:42 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [28/Jun/2018:12:50:42 +0200] [Client 11] Read: status=200, state=6 D [28/Jun/2018:12:50:42 +0200] [Client 11] No authentication data provided. D [28/Jun/2018:12:50:42 +0200] [Client 11] 2.0 CUPS-Get-Default 14 D [28/Jun/2018:12:50:42 +0200] CUPS-Get-Default D [28/Jun/2018:12:50:42 +0200] CUPS-Get-Default client-error-not-found: Aucune imprimante par défaut. D [28/Jun/2018:12:50:42 +0200] [Client 11] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost. D [28/Jun/2018:12:50:42 +0200] [Client 11] Content-Length: 124 D [28/Jun/2018:12:50:42 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:42 +0200] [Client 11] con->http=0x560367729080 D [28/Jun/2018:12:50:42 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=124, response=0x56036770f560(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:42 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:42 +0200] [Client 11] bytes=0, http_state=0, data_remaining=124 D [28/Jun/2018:12:50:42 +0200] [Client 11] Flushing write buffer. D [28/Jun/2018:12:50:42 +0200] [Client 11] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:42 +0200] [Client 11] Waiting for request. D [28/Jun/2018:12:50:42 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [28/Jun/2018:12:50:42 +0200] [Client 11] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [28/Jun/2018:12:50:42 +0200] [Client 11] Closing connection. D [28/Jun/2018:12:50:42 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" I [28/Jun/2018:12:50:43 +0200] Expiring subscriptions... D [28/Jun/2018:12:50:44 +0200] [Client 10] POST / HTTP/1.1 D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [28/Jun/2018:12:50:44 +0200] [Client 10] Read: status=200, state=6 D [28/Jun/2018:12:50:44 +0200] [Client 10] No authentication data provided. D [28/Jun/2018:12:50:44 +0200] [Client 10] 2.0 Get-Printer-Attributes 15 D [28/Jun/2018:12:50:44 +0200] Get-Printer-Attributes ipp://localhost:631/printers/HP_Officejet_6700 D [28/Jun/2018:12:50:44 +0200] [Client 10] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/HP_Officejet_6700) from localhost. D [28/Jun/2018:12:50:44 +0200] [Client 10] Content-Length: 1266 D [28/Jun/2018:12:50:44 +0200] [Client 10] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 I [28/Jun/2018:12:50:44 +0200] Expiring subscriptions... D [28/Jun/2018:12:50:44 +0200] [Client 10] con->http=0x56036771dbf0 D [28/Jun/2018:12:50:44 +0200] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1266, response=0x560367713490(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:44 +0200] [Client 10] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:44 +0200] [Client 10] bytes=0, http_state=0, data_remaining=1266 D [28/Jun/2018:12:50:44 +0200] [Client 10] Flushing write buffer. D [28/Jun/2018:12:50:44 +0200] [Client 10] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:44 +0200] [Client 10] Waiting for request. D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [28/Jun/2018:12:50:44 +0200] [Client 10] POST /printers/HP_Officejet_6700 HTTP/1.1 D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [28/Jun/2018:12:50:44 +0200] [Client 10] Read: status=200, state=6 D [28/Jun/2018:12:50:44 +0200] [Client 10] No authentication data provided. D [28/Jun/2018:12:50:44 +0200] [Client 10] 2.0 Get-Printer-Attributes 16 D [28/Jun/2018:12:50:44 +0200] Get-Printer-Attributes ipp://localhost/printers/HP_Officejet_6700 D [28/Jun/2018:12:50:44 +0200] [Client 10] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_Officejet_6700) from localhost. D [28/Jun/2018:12:50:44 +0200] [Client 10] Content-Length: 40767 D [28/Jun/2018:12:50:44 +0200] [Client 10] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:44 +0200] [Client 10] con->http=0x56036771dbf0 D [28/Jun/2018:12:50:44 +0200] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=40767, response=0x56036770f560(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:44 +0200] [Client 10] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:44 +0200] [Client 10] bytes=0, http_state=0, data_remaining=40767 D [28/Jun/2018:12:50:44 +0200] [Client 10] Flushing write buffer. D [28/Jun/2018:12:50:44 +0200] [Client 10] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:44 +0200] [Client 10] Waiting for request. D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [28/Jun/2018:12:50:44 +0200] [Client 10] POST /printers/HP_Officejet_6700 HTTP/1.1 D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [28/Jun/2018:12:50:44 +0200] [Client 10] Read: status=200, state=6 D [28/Jun/2018:12:50:44 +0200] [Client 10] No authentication data provided. D [28/Jun/2018:12:50:44 +0200] [Client 10] 2.0 Create-Job 17 D [28/Jun/2018:12:50:44 +0200] Create-Job ipp://localhost/printers/HP_Officejet_6700 D [28/Jun/2018:12:50:44 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients" D [28/Jun/2018:12:50:44 +0200] [Job 3155] Applying default options... D [28/Jun/2018:12:50:44 +0200] add_job: requesting-user-name="keikoz" D [28/Jun/2018:12:50:44 +0200] Adding default job-sheets values "none,none"... I [28/Jun/2018:12:50:44 +0200] [Job 3155] Adding start banner page "none". D [28/Jun/2018:12:50:44 +0200] cupsdMarkDirty(----S) D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [28/Jun/2018:12:50:44 +0200] [Job 3155] Queued on "HP_Officejet_6700" by "keikoz". D [28/Jun/2018:12:50:44 +0200] [Client 10] Returning IPP successful-ok for Create-Job (ipp://localhost/printers/HP_Officejet_6700) from localhost. D [28/Jun/2018:12:50:44 +0200] [Client 10] Content-Length: 202 D [28/Jun/2018:12:50:44 +0200] [Client 10] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:44 +0200] [Client 10] con->http=0x56036771dbf0 D [28/Jun/2018:12:50:44 +0200] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=202, response=0x560367704560(IPP_STATE_IDLE), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:44 +0200] [Client 10] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:44 +0200] [Client 10] bytes=0, http_state=0, data_remaining=202 D [28/Jun/2018:12:50:44 +0200] [Client 10] Flushing write buffer. D [28/Jun/2018:12:50:44 +0200] [Client 10] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:44 +0200] [Client 10] Waiting for request. D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [28/Jun/2018:12:50:44 +0200] [Notifier] state=3 D [28/Jun/2018:12:50:44 +0200] [Notifier] JobCreated D [28/Jun/2018:12:50:44 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [28/Jun/2018:12:50:44 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:50:44 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:50:44 +0200] [Client 1] 2.0 Get-Jobs 315 D [28/Jun/2018:12:50:44 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:50:44 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:50:44 +0200] [Client 1] Content-Length: 431 D [28/Jun/2018:12:50:44 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:44 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:50:44 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=431, response=0x560367715280(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:44 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:44 +0200] [Client 1] bytes=0, http_state=0, data_remaining=431 D [28/Jun/2018:12:50:44 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:50:44 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:44 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [28/Jun/2018:12:50:44 +0200] [Client 10] POST /printers/HP_Officejet_6700 HTTP/1.1 D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [28/Jun/2018:12:50:44 +0200] [Client 10] Read: status=200, state=6 D [28/Jun/2018:12:50:44 +0200] [Client 10] No authentication data provided. D [28/Jun/2018:12:50:44 +0200] [Client 10] 2.0 Send-Document 18 D [28/Jun/2018:12:50:44 +0200] [Client 10] Read: status=100, state=6 D [28/Jun/2018:12:50:44 +0200] [Client 10] Read: status=100, state=6 D [28/Jun/2018:12:50:44 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [28/Jun/2018:12:50:44 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:50:44 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:50:44 +0200] [Client 1] 2.0 Get-Jobs 316 D [28/Jun/2018:12:50:44 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:50:44 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:50:44 +0200] [Client 1] Content-Length: 431 D [28/Jun/2018:12:50:44 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:44 +0200] [Client 10] Read: status=100, state=6 D [28/Jun/2018:12:50:44 +0200] Send-Document ipp://localhost:631/printers/HP_Officejet_6700 D [28/Jun/2018:12:50:44 +0200] cupsdIsAuthorized: requesting-user-name="keikoz" D [28/Jun/2018:12:50:44 +0200] [Job 3155] Auto-typing file... D [28/Jun/2018:12:50:44 +0200] [Job 3155] Request file type is application/pdf. D [28/Jun/2018:12:50:44 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [28/Jun/2018:12:50:44 +0200] [Job 3155] File of type application/pdf queued by "keikoz". I [28/Jun/2018:12:50:44 +0200] [Job 3155] Adding end banner page "none". D [28/Jun/2018:12:50:44 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [28/Jun/2018:12:50:44 +0200] [Job 3155] time-at-processing=1530183044 D [28/Jun/2018:12:50:44 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [28/Jun/2018:12:50:44 +0200] Discarding unused printer-state-changed event... D [28/Jun/2018:12:50:44 +0200] [Job 3155] 3 filters for job: D [28/Jun/2018:12:50:44 +0200] [Job 3155] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66) D [28/Jun/2018:12:50:44 +0200] [Job 3155] gstoraster (application/vnd.cups-pdf to application/vnd.cups-raster, cost 99) D [28/Jun/2018:12:50:44 +0200] [Job 3155] hpcups (application/vnd.cups-raster to printer/HP_Officejet_6700, cost 0) D [28/Jun/2018:12:50:44 +0200] [Job 3155] job-sheets=none,none D [28/Jun/2018:12:50:44 +0200] [Job 3155] argv[0]="HP_Officejet_6700" D [28/Jun/2018:12:50:44 +0200] [Job 3155] argv[1]="3155" D [28/Jun/2018:12:50:44 +0200] [Job 3155] argv[2]="keikoz" D [28/Jun/2018:12:50:44 +0200] [Job 3155] argv[3]="PlanningTravail.odt" D [28/Jun/2018:12:50:44 +0200] [Job 3155] argv[4]="1" D [28/Jun/2018:12:50:44 +0200] [Job 3155] argv[5]="Duplex=None InputSlot=Tray1 PageSize=A4.Duplex job-uuid=urn:uuid:85bae156-3acd-3fd7-6141-240a734334ad job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1530183044 time-at-processing=1530183044 document-name-supplied=DS9pHR" D [28/Jun/2018:12:50:44 +0200] [Job 3155] argv[6]="/var/spool/cups/d03155-001" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[1]="CUPS_DATADIR=/usr/share/cups" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[6]="CUPS_SERVERROOT=/etc/cups" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[7]="CUPS_STATEDIR=/run/cups" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[8]="HOME=/var/spool/cups/tmp" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[10]="SERVER_ADMIN=root@kdesktop" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[11]="SOFTWARE=CUPS/2.2.8" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[12]="TMPDIR=/var/spool/cups/tmp" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[13]="USER=root" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[14]="CUPS_MAX_MESSAGE=2047" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[15]="CUPS_SERVER=/run/cups/cups.sock" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[16]="CUPS_ENCRYPTION=IfRequested" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[17]="IPP_PORT=631" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[18]="CHARSET=utf-8" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[19]="LANG=fr_FR.UTF-8" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[20]="PPD=/etc/cups/ppd/HP_Officejet_6700.ppd" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[21]="RIP_MAX_CACHE=128m" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[22]="CONTENT_TYPE=application/pdf" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[23]="DEVICE_URI=socket://192.168.0.100:9100" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[24]="PRINTER_INFO=HP Officejet 6700" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[25]="PRINTER_LOCATION=Bureau" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[26]="PRINTER=HP_Officejet_6700" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[27]="PRINTER_STATE_REASONS=none" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[28]="CUPS_FILETYPE=document" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-raster" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[30]="AUTH_I****" I [28/Jun/2018:12:50:44 +0200] [Job 3155] Started filter /usr/lib/cups/filter/pdftopdf (PID 25052) I [28/Jun/2018:12:50:44 +0200] [Job 3155] Started filter /usr/lib/cups/filter/gstoraster (PID 25053) I [28/Jun/2018:12:50:44 +0200] [Job 3155] Started filter /usr/lib/cups/filter/hpcups (PID 25054) I [28/Jun/2018:12:50:44 +0200] [Job 3155] Started backend /usr/lib/cups/backend/socket (PID 25055) D [28/Jun/2018:12:50:44 +0200] cupsdMarkDirty(----S) D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files" D [28/Jun/2018:12:50:44 +0200] [Client 10] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/HP_Officejet_6700) from localhost. D [28/Jun/2018:12:50:44 +0200] [Client 10] Content-Length: 172 D [28/Jun/2018:12:50:44 +0200] [Client 10] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:44 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:50:44 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=431, response=0x56036770bb50(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:44 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:44 +0200] [Client 1] bytes=0, http_state=0, data_remaining=431 D [28/Jun/2018:12:50:44 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:50:44 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:44 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [28/Jun/2018:12:50:44 +0200] [Client 10] con->http=0x56036771dbf0 D [28/Jun/2018:12:50:44 +0200] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=172, response=0x560367724db0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:44 +0200] [Client 10] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:44 +0200] [Client 10] bytes=0, http_state=0, data_remaining=172 D [28/Jun/2018:12:50:44 +0200] [Client 10] Flushing write buffer. D [28/Jun/2018:12:50:44 +0200] [Client 10] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:44 +0200] [Client 10] Waiting for request. D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [28/Jun/2018:12:50:44 +0200] [Job 3155] OUTFORMAT=\"(null)\", so output format will be CUPS/PWG Raster D [28/Jun/2018:12:50:44 +0200] [Notifier] state=3 D [28/Jun/2018:12:50:44 +0200] [Notifier] JobState D [28/Jun/2018:12:50:44 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:44 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:50:44 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:50:44 +0200] [Client 1] 2.0 Get-Jobs 317 D [28/Jun/2018:12:50:44 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:50:44 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:50:44 +0200] [Client 1] Content-Length: 435 D [28/Jun/2018:12:50:44 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:44 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:50:44 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=435, response=0x560367715280(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:44 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:44 +0200] [Client 1] bytes=0, http_state=0, data_remaining=435 D [28/Jun/2018:12:50:44 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:50:44 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:44 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [28/Jun/2018:12:50:44 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:44 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:50:44 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:50:44 +0200] [Client 1] 2.0 Get-Jobs 318 D [28/Jun/2018:12:50:44 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:50:44 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:50:44 +0200] [Client 1] Content-Length: 435 D [28/Jun/2018:12:50:44 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:44 +0200] [Job 3155] STATE: +connecting-to-device D [28/Jun/2018:12:50:44 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients, printing jobs, and dirty files" D [28/Jun/2018:12:50:44 +0200] [Job 3155] Looking up \"192.168.0.100\"... D [28/Jun/2018:12:50:44 +0200] [Job 3155] STATE: -connecting-to-device D [28/Jun/2018:12:50:44 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files" D [28/Jun/2018:12:50:44 +0200] [Job 3155] 192.168.0.100=192.168.0.100 D [28/Jun/2018:12:50:44 +0200] Discarding unused printer-state-changed event... D [28/Jun/2018:12:50:44 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:50:44 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=435, response=0x560367704560(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:44 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:44 +0200] [Client 1] bytes=0, http_state=0, data_remaining=435 D [28/Jun/2018:12:50:44 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:50:44 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:44 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [28/Jun/2018:12:50:44 +0200] [Job 3155] prtGeneralCurrentLocalization type is 5, expected 2! D [28/Jun/2018:12:50:44 +0200] [Job 3155] backendWaitLoop(snmp_fd=5, addr=0x55b5afe6cdf8, side_cb=0x55b5add28ec0) D [28/Jun/2018:12:50:44 +0200] [Job 3155] pdftopdf: Last filter determined by the PPD: hpcups; FINAL_CONTENT_TYPE: application/vnd.cups-raster => pdftopdf will not log pages in page_log. D [28/Jun/2018:12:50:44 +0200] [Job 3155] PID 25052 (/usr/lib/cups/filter/pdftopdf) exited with no errors. D [28/Jun/2018:12:50:44 +0200] [Job 3155] Color Manager: Calibration Mode/Off D [28/Jun/2018:12:50:44 +0200] [Job 3155] Calling FindDeviceById(cups-HP_Officejet_6700) D [28/Jun/2018:12:50:44 +0200] [Job 3155] Found device /org/freedesktop/ColorManager/devices/cups_HP_Officejet_6700 D [28/Jun/2018:12:50:44 +0200] [Job 3155] Calling org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors) D [28/Jun/2018:12:50:44 +0200] [Job 3155] Calling FindDeviceById(cups-HP_Officejet_6700) D [28/Jun/2018:12:50:44 +0200] [Job 3155] Found device /org/freedesktop/ColorManager/devices/cups_HP_Officejet_6700 D [28/Jun/2018:12:50:44 +0200] [Job 3155] Calling GetProfileForQualifiers(RGB.Plain....) D [28/Jun/2018:12:50:44 +0200] [Job 3155] Found profile /org/freedesktop/ColorManager/profiles/HP_Officejet_6700_RGB__ D [28/Jun/2018:12:50:44 +0200] [Job 3155] Calling org.freedesktop.ColorManager.Profile.Get(Filename) D [28/Jun/2018:12:50:44 +0200] [Job 3155] Use profile filename: \'\' D [28/Jun/2018:12:50:44 +0200] [Job 3155] Color Manager: ICC Profile: D [28/Jun/2018:12:50:44 +0200] [Job 3155] Ghostscript using Any-Part-of-Pixel method to fill paths. D [28/Jun/2018:12:50:44 +0200] [Job 3155] Ghostscript command line: gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -dNOMEDIAATTRS -dShowAcroForm -sstdout=%stderr -sOutputFile=%stdout -sDEVICE=cups -sMediaType=Plain -sOutputType=0 -r600x600 -dMediaPosition=1 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=841 -dcupsBitsPerColor=8 -dcupsColorOrder=0 -dcupsColorSpace=17 -dcupsInteger0=26 -scupsPageSizeName=A4.Duplex -I/usr/share/cups/fonts -c \'<>setpagedevice\' -f -_ D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[0]=\"CUPS_CACHEDIR=/var/cache/cups\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[1]=\"CUPS_DATADIR=/usr/share/cups\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[2]=\"CUPS_DOCROOT=/usr/share/cups/doc\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[3]=\"CUPS_FONTPATH=/usr/share/cups/fonts\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[4]=\"CUPS_REQUESTROOT=/var/spool/cups\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[5]=\"CUPS_SERVERBIN=/usr/lib/cups\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[6]=\"CUPS_SERVERROOT=/etc/cups\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[7]=\"CUPS_STATEDIR=/run/cups\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[8]=\"HOME=/var/spool/cups/tmp\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[9]=\"PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[10]=\"SERVER_ADMIN=root@kdesktop\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[11]=\"SOFTWARE=CUPS/2.2.8\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[12]=\"TMPDIR=/var/spool/cups/tmp\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[13]=\"USER=root\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[14]=\"CUPS_MAX_MESSAGE=2047\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[15]=\"CUPS_SERVER=/run/cups/cups.sock\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[16]=\"CUPS_ENCRYPTION=IfRequested\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[17]=\"IPP_PORT=631\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[18]=\"CHARSET=utf-8\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[19]=\"LANG=fr_FR.UTF-8\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[20]=\"PPD=/etc/cups/ppd/HP_Officejet_6700.ppd\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[21]=\"RIP_MAX_CACHE=128m\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[22]=\"CONTENT_TYPE=application/pdf\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[23]=\"DEVICE_URI=socket://192.168.0.100:9100\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[24]=\"PRINTER_INFO=HP Officejet 6700\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[25]=\"PRINTER_LOCATION=Bureau\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[26]=\"PRINTER=HP_Officejet_6700\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[27]=\"PRINTER_STATE_REASONS=none\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[28]=\"CUPS_FILETYPE=document\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[29]=\"FINAL_CONTENT_TYPE=application/vnd.cups-raster\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] envp[30]=\"AUTH_INFO_REQUIRED=none\" D [28/Jun/2018:12:50:44 +0200] [Job 3155] Start rendering... D [28/Jun/2018:12:50:44 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:44 +0200] [Job 3155] Set job-printer-state-message to "Start rendering...", current level=INFO D [28/Jun/2018:12:50:44 +0200] [Job 3155] Processing page 1... D [28/Jun/2018:12:50:44 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:44 +0200] [Job 3155] Set job-printer-state-message to "Processing page 1...", current level=INFO D [28/Jun/2018:12:50:44 +0200] cupsdMarkDirty(----S) D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:44 +0200] Discarding unused printer-state-changed event... D [28/Jun/2018:12:50:44 +0200] [Notifier] state=3 D [28/Jun/2018:12:50:44 +0200] [Notifier] JobProgress D [28/Jun/2018:12:50:44 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:44 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:50:44 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:50:44 +0200] [Client 1] 2.0 Get-Jobs 319 D [28/Jun/2018:12:50:44 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:50:44 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:50:44 +0200] [Client 1] Content-Length: 485 D [28/Jun/2018:12:50:44 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:44 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:50:44 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=485, response=0x56036770bd20(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:44 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:44 +0200] [Client 1] bytes=0, http_state=0, data_remaining=485 D [28/Jun/2018:12:50:44 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:50:44 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:44 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [28/Jun/2018:12:50:44 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:44 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:50:44 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:50:44 +0200] [Client 1] 2.0 Get-Jobs 320 D [28/Jun/2018:12:50:44 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:50:44 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:50:44 +0200] [Client 1] Content-Length: 485 D [28/Jun/2018:12:50:44 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:44 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:50:44 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=485, response=0x560367724df0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:44 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:44 +0200] [Client 1] bytes=0, http_state=0, data_remaining=485 D [28/Jun/2018:12:50:44 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:50:44 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:44 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:50:44 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [28/Jun/2018:12:50:45 +0200] [Job 3155] PAGE: 1 1\r D [28/Jun/2018:12:50:45 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:50:45 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:45 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:50:45 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:45 +0200] cupsdMarkDirty(----S) D [28/Jun/2018:12:50:45 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" I [28/Jun/2018:12:50:45 +0200] Expiring subscriptions... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Connecting to 192.168.0.100:9100 D [28/Jun/2018:12:50:45 +0200] [Notifier] state=3 D [28/Jun/2018:12:50:45 +0200] [Notifier] JobProgress D [28/Jun/2018:12:50:45 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:50:45 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:45 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:50:45 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:50:45 +0200] [Client 1] 2.0 Get-Jobs 321 D [28/Jun/2018:12:50:45 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:50:45 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:50:45 +0200] [Client 1] Content-Length: 485 D [28/Jun/2018:12:50:45 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:45 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:50:45 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=485, response=0x56036770bd20(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:45 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:45 +0200] [Client 1] bytes=0, http_state=0, data_remaining=485 D [28/Jun/2018:12:50:45 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:50:45 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:45 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:50:45 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [28/Jun/2018:12:50:45 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:50:45 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:45 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:50:45 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:50:45 +0200] [Client 1] 2.0 Get-Jobs 322 D [28/Jun/2018:12:50:45 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:50:45 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:50:45 +0200] [Client 1] Content-Length: 485 D [28/Jun/2018:12:50:45 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:45 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:50:45 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=485, response=0x56036772a3e0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:45 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:45 +0200] [Client 1] bytes=0, http_state=0, data_remaining=485 D [28/Jun/2018:12:50:45 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:50:45 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:45 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:50:45 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [28/Jun/2018:12:50:45 +0200] [Job 3155] Connexion à l\'imprimante en cours. D [28/Jun/2018:12:50:45 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:50:45 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:45 +0200] [Job 3155] Set job-printer-state-message to "Connexion à l\'imprimante en cours.", current level=INFO D [28/Jun/2018:12:50:45 +0200] cupsdMarkDirty(----S) D [28/Jun/2018:12:50:45 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:45 +0200] Discarding unused printer-state-changed event... D [28/Jun/2018:12:50:45 +0200] [Notifier] state=3 D [28/Jun/2018:12:50:45 +0200] [Notifier] JobProgress D [28/Jun/2018:12:50:45 +0200] [Job 3155] STATE: -connecting-to-device D [28/Jun/2018:12:50:45 +0200] [Job 3155] Connecté à l\'imprimante. D [28/Jun/2018:12:50:45 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:50:45 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:45 +0200] [Job 3155] Set job-printer-state-message to "Connecté à l\'imprimante.", current level=INFO D [28/Jun/2018:12:50:45 +0200] [Job 3155] Connected to 192.168.0.100:9100... D [28/Jun/2018:12:50:45 +0200] [Job 3155] backendRunLoop(print_fd=0, device_fd=6, snmp_fd=5, addr=0x55b5afe6cdf8, use_bc=1, side_cb=0x55b5add28ec0) D [28/Jun/2018:12:50:45 +0200] cupsdMarkDirty(----S) D [28/Jun/2018:12:50:45 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:45 +0200] Discarding unused printer-state-changed event... D [28/Jun/2018:12:50:45 +0200] [Notifier] state=3 D [28/Jun/2018:12:50:45 +0200] [Notifier] JobProgress D [28/Jun/2018:12:50:45 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:50:45 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:45 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:50:45 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:50:45 +0200] [Client 1] 2.0 Get-Jobs 323 D [28/Jun/2018:12:50:45 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:50:45 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:50:45 +0200] [Client 1] Content-Length: 491 D [28/Jun/2018:12:50:45 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:45 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:50:45 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=491, response=0x560367722f00(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:45 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:45 +0200] [Client 1] bytes=0, http_state=0, data_remaining=491 D [28/Jun/2018:12:50:45 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:50:45 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:45 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:50:45 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [28/Jun/2018:12:50:45 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:50:45 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:45 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:50:45 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:50:45 +0200] [Client 1] 2.0 Get-Jobs 324 D [28/Jun/2018:12:50:45 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:50:45 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:50:45 +0200] [Client 1] Content-Length: 491 D [28/Jun/2018:12:50:45 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:45 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:50:45 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=491, response=0x56036770bd20(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:45 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:45 +0200] [Client 1] bytes=0, http_state=0, data_remaining=491 D [28/Jun/2018:12:50:45 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:50:45 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:45 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:50:45 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 1731 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 1731 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 1736 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 1736 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 1747 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 1747 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 1694 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 1694 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 1798 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 1798 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 1693 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 1693 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 1792 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 1792 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:45 +0200] [Job 3155] Read 8192 bytes of print data... I [28/Jun/2018:12:50:46 +0200] Expiring subscriptions... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Read 8192 bytes of print data... I [28/Jun/2018:12:50:55 +0200] Expiring subscriptions... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Processing page 2... D [28/Jun/2018:12:50:55 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:50:55 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:55 +0200] [Job 3155] Set job-printer-state-message to "Processing page 2...", current level=INFO D [28/Jun/2018:12:50:55 +0200] cupsdMarkDirty(----S) D [28/Jun/2018:12:50:55 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:55 +0200] Discarding unused printer-state-changed event... D [28/Jun/2018:12:50:55 +0200] [Notifier] state=3 D [28/Jun/2018:12:50:55 +0200] [Notifier] JobProgress D [28/Jun/2018:12:50:55 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:50:55 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:55 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:50:55 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:50:55 +0200] [Client 1] 2.0 Get-Jobs 325 D [28/Jun/2018:12:50:55 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:50:55 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:50:55 +0200] [Client 1] Content-Length: 485 D [28/Jun/2018:12:50:55 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:55 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:50:55 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=485, response=0x560367723050(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:55 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:55 +0200] [Client 1] bytes=0, http_state=0, data_remaining=485 D [28/Jun/2018:12:50:55 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:50:55 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:55 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:50:55 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [28/Jun/2018:12:50:55 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:50:55 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:55 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:50:55 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:50:55 +0200] [Client 1] 2.0 Get-Jobs 326 D [28/Jun/2018:12:50:55 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:50:55 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:50:55 +0200] [Client 1] Content-Length: 485 D [28/Jun/2018:12:50:55 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:55 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:50:55 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=485, response=0x560367723470(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:55 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:55 +0200] [Client 1] bytes=0, http_state=0, data_remaining=485 D [28/Jun/2018:12:50:55 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:50:55 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:55 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:50:55 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [28/Jun/2018:12:50:55 +0200] [Job 3155] Rendering completed D [28/Jun/2018:12:50:55 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:50:55 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:55 +0200] [Job 3155] Set job-printer-state-message to "Rendering completed", current level=INFO D [28/Jun/2018:12:50:55 +0200] cupsdMarkDirty(----S) D [28/Jun/2018:12:50:55 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:55 +0200] Discarding unused printer-state-changed event... D [28/Jun/2018:12:50:55 +0200] [Notifier] state=3 D [28/Jun/2018:12:50:55 +0200] [Notifier] JobProgress D [28/Jun/2018:12:50:55 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:50:55 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:55 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:50:55 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:50:55 +0200] [Client 1] 2.0 Get-Jobs 327 D [28/Jun/2018:12:50:55 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:50:55 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:50:55 +0200] [Client 1] Content-Length: 484 D [28/Jun/2018:12:50:55 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:55 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:50:55 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=484, response=0x560367722f00(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:55 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:55 +0200] [Client 1] bytes=0, http_state=0, data_remaining=484 D [28/Jun/2018:12:50:55 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:50:55 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:55 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:50:55 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [28/Jun/2018:12:50:55 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:50:55 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:55 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:50:55 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:50:55 +0200] [Client 1] 2.0 Get-Jobs 328 D [28/Jun/2018:12:50:55 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:50:55 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:50:55 +0200] [Client 1] Content-Length: 484 D [28/Jun/2018:12:50:55 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:55 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:50:55 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=484, response=0x560367723600(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:55 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:55 +0200] [Client 1] bytes=0, http_state=0, data_remaining=484 D [28/Jun/2018:12:50:55 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:50:55 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:55 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:50:55 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [28/Jun/2018:12:50:55 +0200] [Job 3155] PID 25053 (/usr/lib/cups/filter/gstoraster) exited with no errors. D [28/Jun/2018:12:50:55 +0200] [Job 3155] PID 25054 (/usr/lib/cups/filter/hpcups) exited with no errors. D [28/Jun/2018:12:50:55 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Read 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Wrote 8192 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Read 3221 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Wrote 3221 bytes of print data... D [28/Jun/2018:12:50:55 +0200] [Job 3155] STATE: +cups-waiting-for-job-completed D [28/Jun/2018:12:50:55 +0200] cupsdMarkDirty(P----) D [28/Jun/2018:12:50:55 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:55 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:50:55 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:55 +0200] Discarding unused printer-state-changed event... D [28/Jun/2018:12:50:55 +0200] [Job 3155] Waiting for printer to finish. D [28/Jun/2018:12:50:55 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:50:55 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:55 +0200] [Job 3155] Set job-printer-state-message to "Waiting for printer to finish.", current level=INFO D [28/Jun/2018:12:50:55 +0200] cupsdMarkDirty(----S) D [28/Jun/2018:12:50:55 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:55 +0200] Discarding unused printer-state-changed event... D [28/Jun/2018:12:50:55 +0200] [Notifier] state=3 D [28/Jun/2018:12:50:55 +0200] [Notifier] JobProgress D [28/Jun/2018:12:50:55 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:50:55 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:55 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:50:55 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:50:55 +0200] [Client 1] 2.0 Get-Jobs 329 D [28/Jun/2018:12:50:55 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:50:55 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:50:55 +0200] [Client 1] Content-Length: 495 D [28/Jun/2018:12:50:55 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:55 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:50:55 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=495, response=0x560367723950(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:55 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:55 +0200] [Client 1] bytes=0, http_state=0, data_remaining=495 D [28/Jun/2018:12:50:55 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:50:55 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:55 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:50:55 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [28/Jun/2018:12:50:55 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:50:55 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:55 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:50:55 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:50:55 +0200] [Client 1] 2.0 Get-Jobs 330 D [28/Jun/2018:12:50:55 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:50:55 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:50:55 +0200] [Client 1] Content-Length: 495 D [28/Jun/2018:12:50:55 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:55 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:50:55 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=495, response=0x560367722f00(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:55 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:55 +0200] [Client 1] bytes=0, http_state=0, data_remaining=495 D [28/Jun/2018:12:50:55 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:50:55 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:55 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:50:55 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" I [28/Jun/2018:12:50:56 +0200] Expiring subscriptions... I [28/Jun/2018:12:50:59 +0200] Expiring subscriptions... D [28/Jun/2018:12:50:59 +0200] [Job 3155] PID 25055 (/usr/lib/cups/backend/socket) exited with no errors. D [28/Jun/2018:12:50:59 +0200] cupsdMarkDirty(P----) D [28/Jun/2018:12:50:59 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:59 +0200] [Job 3155] time-at-completed=1530183059 D [28/Jun/2018:12:50:59 +0200] cupsdMarkDirty(----S) D [28/Jun/2018:12:50:59 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" I [28/Jun/2018:12:50:59 +0200] [Job 3155] Job completed. I [28/Jun/2018:12:50:59 +0200] Expiring subscriptions... D [28/Jun/2018:12:50:59 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:50:59 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:59 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:59 +0200] Discarding unused printer-state-changed event... D [28/Jun/2018:12:50:59 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:50:59 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:59 +0200] cupsdMarkDirty(---J-) D [28/Jun/2018:12:50:59 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:59 +0200] [Notifier] state=3 D [28/Jun/2018:12:50:59 +0200] [Notifier] JobCompleted D [28/Jun/2018:12:50:59 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:50:59 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files" D [28/Jun/2018:12:50:59 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:50:59 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:50:59 +0200] [Client 1] 2.0 Get-Jobs 331 D [28/Jun/2018:12:50:59 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:50:59 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:50:59 +0200] [Client 1] Content-Length: 75 D [28/Jun/2018:12:50:59 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:59 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:50:59 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x560367723e30(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:59 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:59 +0200] [Client 1] bytes=0, http_state=0, data_remaining=75 D [28/Jun/2018:12:50:59 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:50:59 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:59 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:50:59 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [28/Jun/2018:12:50:59 +0200] [Client 1] POST / HTTP/1.1 D [28/Jun/2018:12:50:59 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [28/Jun/2018:12:50:59 +0200] [Client 1] Read: status=200, state=6 D [28/Jun/2018:12:50:59 +0200] [Client 1] No authentication data provided. D [28/Jun/2018:12:50:59 +0200] [Client 1] 2.0 Get-Jobs 332 D [28/Jun/2018:12:50:59 +0200] Get-Jobs ipp://keikoz@localhost:631/printers/ D [28/Jun/2018:12:50:59 +0200] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://keikoz@localhost:631/printers/) from localhost. D [28/Jun/2018:12:50:59 +0200] [Client 1] Content-Length: 75 D [28/Jun/2018:12:50:59 +0200] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [28/Jun/2018:12:50:59 +0200] [Client 1] con->http=0x560367717970 D [28/Jun/2018:12:50:59 +0200] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x5603677034b0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [28/Jun/2018:12:50:59 +0200] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [28/Jun/2018:12:50:59 +0200] [Client 1] bytes=0, http_state=0, data_remaining=75 D [28/Jun/2018:12:50:59 +0200] [Client 1] Flushing write buffer. D [28/Jun/2018:12:50:59 +0200] [Client 1] New state is HTTP_STATE_WAITING D [28/Jun/2018:12:50:59 +0200] [Client 1] Waiting for request. D [28/Jun/2018:12:50:59 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" I [28/Jun/2018:12:51:00 +0200] Expiring subscriptions... D [28/Jun/2018:12:51:00 +0200] [Job 3155] Unloading... I [28/Jun/2018:12:51:15 +0200] Saving printers.conf... I [28/Jun/2018:12:51:15 +0200] Saving job.cache... I [28/Jun/2018:12:51:15 +0200] Saving subscriptions.conf... D [28/Jun/2018:12:51:15 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files" I [28/Jun/2018:12:51:15 +0200] Expiring subscriptions... I [28/Jun/2018:12:55:45 +0200] Expiring subscriptions... D [28/Jun/2018:12:55:45 +0200] cupsdAddCert: Adding certificate for PID 0 D [28/Jun/2018:12:55:45 +0200] Closing client 10 after 300 seconds of inactivity. D [28/Jun/2018:12:55:45 +0200] [Client 10] Closing connection. D [28/Jun/2018:12:55:45 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [28/Jun/2018:12:55:45 +0200] Report: clients=1 D [28/Jun/2018:12:55:45 +0200] Report: jobs=28 D [28/Jun/2018:12:55:45 +0200] Report: jobs-active=0 D [28/Jun/2018:12:55:45 +0200] Report: printers=2 D [28/Jun/2018:12:55:45 +0200] Report: stringpool-string-count=3906 D [28/Jun/2018:12:55:45 +0200] Report: stringpool-alloc-bytes=12160 D [28/Jun/2018:12:55:45 +0200] Report: stringpool-total-bytes=75808 I [28/Jun/2018:12:56:00 +0200] Expiring subscriptions... D [28/Jun/2018:12:56:00 +0200] Closing client 1 after 300 seconds of inactivity. D [28/Jun/2018:12:56:00 +0200] [Client 1] Closing connection. D [28/Jun/2018:12:56:00 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"