[cups] HP printer support with yocto embedded system

[zhangying]

I use the embedded Linux yocto cross-compile environment.Hplip version is 3.18.3.
My rootfs already contains cups,hplip,ghost script,foomatic, cups_filter,etc., which are needed to drive the printer. As I understand it, cups and hplip can be used together. I would use cups+ with its own generic PPD to drive some generic usb printers, but that didn't work for me, so I came up with hplip. Right now I can't use hplip as smoothly as cups.
Hplip can identify the model of the printer and generate PPD, but when using cups to print PDF, you can't type anything, error_log is as follows, which may be a bit long, please check.
root at imx6qsabresd:/# lp -d HP_LaserJet_M1536dnf_MFP /zy/m6168\(8\)\(1\)\(2\).pdf
request id is HP_LaserJet_M1536dnf_MFP-8 (1 file(s))
I [28/Oct/2019:17:20:09 +0000] Listening to [v1.::1]:631 (IPv6)
I [28/Oct/2019:17:20:09 +0000] Listening to 127.0.0.1:631 (IPv4)
I [28/Oct/2019:17:20:09 +0000] Listening to /var/run/cups/cups.sock (Domain)
I [28/Oct/2019:17:20:09 +0000] Remote access is disabled.
D [28/Oct/2019:17:20:09 +0000] Added auto ServerAlias imx6qsabresd
I [28/Oct/2019:17:20:09 +0000] Loaded configuration file "/etc/cups/cupsd.conf"
D [28/Oct/2019:17:20:09 +0000] Using keychain "/etc/cups/ssl" for server name "imx6qsabresd".
D [28/Oct/2019:17:20:09 +0000] Creating missing directory "/var/run/cups"
D [28/Oct/2019:17:20:09 +0000] Repairing ownership of "/var/run/cups"
D [28/Oct/2019:17:20:09 +0000] Repairing access permissions of "/var/run/cups"
D [28/Oct/2019:17:20:09 +0000] Creating missing directory "/var/run/cups/certs"
D [28/Oct/2019:17:20:09 +0000] Repairing ownership of "/var/run/cups/certs"
D [28/Oct/2019:17:20:09 +0000] Repairing access permissions of "/var/run/cups/certs"
I [28/Oct/2019:17:20:09 +0000] Using default TempDir of /var/spool/cups/tmp...
I [28/Oct/2019:17:20:09 +0000] Configured for up to 100 clients.
I [28/Oct/2019:17:20:09 +0000] Allowing up to 100 client connections per host.
I [28/Oct/2019:17:20:09 +0000] Using policy "default" as the default.
I [28/Oct/2019:17:20:09 +0000] Full reload is required.
I [28/Oct/2019:17:20:09 +0000] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 83 types, 113 filters...
D [28/Oct/2019:17:20:09 +0000] Loading printer HP_LaserJet_M1536dnf_MFP...
D [28/Oct/2019:17:20:09 +0000] cupsdMarkDirty(P----)
D [28/Oct/2019:17:20:09 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Not busy"
D [28/Oct/2019:17:20:09 +0000] load_ppd: Loading /etc/cups/ppd/HP_LaserJet_M1536dnf_MFP.ppd...
D [28/Oct/2019:17:20:09 +0000] cupsdRegisterPrinter(p=0x56bd03d0(HP_LaserJet_M1536dnf_MFP))
D [28/Oct/2019:17:20:09 +0000] Loading printer HP_LaserJet_Pro_MFP_M126nw...
D [28/Oct/2019:17:20:09 +0000] load_ppd: Loading /var/cache/cups/HP_LaserJet_Pro_MFP_M126nw.data...
D [28/Oct/2019:17:20:09 +0000] cupsdRegisterPrinter(p=0x56bc17e0(HP_LaserJet_Pro_MFP_M126nw))
I [28/Oct/2019:17:20:09 +0000] Loading job cache file "/var/cache/cups/job.cache"...
E [28/Oct/2019:17:20:09 +0000] [Job 6] Files have gone away.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 5 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 6 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 7 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 8 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 9 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 10 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 11 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 12 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 13 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 14 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 15 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] [Job 7] Files have gone away.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 17 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 18 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 19 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 20 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 21 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 22 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 23 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 24 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 25 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 26 of /var/cache/cups/job.cache.
E [28/Oct/2019:17:20:09 +0000] Missing <Job #> directive on line 27 of /var/cache/cups/job.cache.
I [28/Oct/2019:17:20:09 +0000] Full reload complete.
D [28/Oct/2019:17:20:09 +0000] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
I [28/Oct/2019:17:20:09 +0000] Cleaning out old files in "/var/spool/cups/tmp".
D [28/Oct/2019:17:20:09 +0000] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
I [28/Oct/2019:17:20:09 +0000] Cleaning out old files in "/var/cache/cups".
D [28/Oct/2019:17:20:09 +0000] Calling FindDeviceById(cups-HP_LaserJet_M1536dnf_MFP)
D [28/Oct/2019:17:20:09 +0000] FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [28/Oct/2019:17:20:09 +0000] Calling FindDeviceById(cups-HP_LaserJet_Pro_MFP_M126nw)
D [28/Oct/2019:17:20:09 +0000] FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [28/Oct/2019:17:20:09 +0000] Using profile ID "HP_LaserJet_Pro_MFP_M126nw-Gray..".
D [28/Oct/2019:17:20:09 +0000] Calling CreateProfile(HP_LaserJet_Pro_MFP_M126nw-Gray..,temp)
W [28/Oct/2019:17:20:09 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
D [28/Oct/2019:17:20:09 +0000] Using profile ID "HP_LaserJet_Pro_MFP_M126nw-RGB..".
D [28/Oct/2019:17:20:09 +0000] Calling CreateProfile(HP_LaserJet_Pro_MFP_M126nw-RGB..,temp)
W [28/Oct/2019:17:20:09 +0000] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
I [28/Oct/2019:17:20:09 +0000] Registering ICC color profiles for "HP_LaserJet_Pro_MFP_M126nw".
D [28/Oct/2019:17:20:09 +0000] Calling CreateDevice(cups-HP_LaserJet_Pro_MFP_M126nw,temp)
W [28/Oct/2019:17:20:09 +0000] CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
I [28/Oct/2019:17:20:09 +0000] Listening to [v1.::1]:631 on fd 9...
I [28/Oct/2019:17:20:09 +0000] Listening to 127.0.0.1:631 on fd 10...
I [28/Oct/2019:17:20:09 +0000] Listening to /var/run/cups/cups.sock on fd 11...
I [28/Oct/2019:17:20:09 +0000] Resuming new connection processing...
D [28/Oct/2019:17:20:09 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:09 +0000] cupsdAddCert: Adding certificate for PID 0
D [28/Oct/2019:17:20:09 +0000] Discarding unused server-started event...
D [28/Oct/2019:17:20:10 +0000] Report: clients=0
D [28/Oct/2019:17:20:10 +0000] Report: jobs=0
D [28/Oct/2019:17:20:10 +0000] Report: jobs-active=0
D [28/Oct/2019:17:20:10 +0000] Report: printers=2
D [28/Oct/2019:17:20:10 +0000] Report: stringpool-string-count=7868
D [28/Oct/2019:17:20:10 +0000] Report: stringpool-alloc-bytes=10120
D [28/Oct/2019:17:20:10 +0000] Report: stringpool-total-bytes=142984
D [28/Oct/2019:17:20:28 +0000] [Client 1] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:28 +0000] [Client 1] Waiting for request.
D [28/Oct/2019:17:20:28 +0000] [Client 1] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:28 +0000] [Client 1] Closing connection.
D [28/Oct/2019:17:20:28 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:29 +0000] [Client 2] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:29 +0000] [Client 2] Waiting for request.
D [28/Oct/2019:17:20:29 +0000] [Client 2] POST / HTTP/1.1
D [28/Oct/2019:17:20:29 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:29 +0000] [Client 2] Read: status=200
D [28/Oct/2019:17:20:29 +0000] [Client 2] No authentication data provided.
D [28/Oct/2019:17:20:29 +0000] [Client 2] 2.0 CUPS-Get-Printers 1
D [28/Oct/2019:17:20:29 +0000] CUPS-Get-Printers
D [28/Oct/2019:17:20:29 +0000] [Client 2] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Oct/2019:17:20:29 +0000] [Client 2] Content-Length: 2006
D [28/Oct/2019:17:20:29 +0000] [Client 2] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:29 +0000] [Client 2] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:29 +0000] [Client 2] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2006, response=0x56c4e170(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:29 +0000] [Client 2] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:29 +0000] [Client 2] bytes=0, http_state=0, data_remaining=2006
D [28/Oct/2019:17:20:29 +0000] [Client 2] Flushing write buffer.
D [28/Oct/2019:17:20:29 +0000] [Client 2] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:29 +0000] [Client 2] Waiting for request.
D [28/Oct/2019:17:20:29 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Oct/2019:17:20:29 +0000] [Client 2] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:29 +0000] [Client 2] Closing connection.
D [28/Oct/2019:17:20:29 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:29 +0000] [Client 3] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:29 +0000] [Client 3] Waiting for request.
D [28/Oct/2019:17:20:29 +0000] [Client 3] POST / HTTP/1.1
D [28/Oct/2019:17:20:29 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:29 +0000] [Client 3] Read: status=200
D [28/Oct/2019:17:20:29 +0000] [Client 3] No authentication data provided.
D [28/Oct/2019:17:20:29 +0000] [Client 3] Read: status=100
D [28/Oct/2019:17:20:29 +0000] [Client 3] Read: status=100
D [28/Oct/2019:17:20:29 +0000] [Client 3] Read: status=100
D [28/Oct/2019:17:20:29 +0000] [Client 3] Read: status=100
D [28/Oct/2019:17:20:29 +0000] [Client 3] 2.0 CUPS-Get-Default 2
D [28/Oct/2019:17:20:29 +0000] CUPS-Get-Default
D [28/Oct/2019:17:20:29 +0000] CUPS-Get-Default client-error-not-found: No default printer.
D [28/Oct/2019:17:20:29 +0000] [Client 3] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [28/Oct/2019:17:20:29 +0000] [Client 3] Content-Length: 110
D [28/Oct/2019:17:20:29 +0000] [Client 3] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:29 +0000] [Client 3] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:29 +0000] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=110, response=0x56c4e1d0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:29 +0000] [Client 3] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:29 +0000] [Client 3] bytes=0, http_state=0, data_remaining=110
D [28/Oct/2019:17:20:29 +0000] [Client 3] Flushing write buffer.
D [28/Oct/2019:17:20:29 +0000] [Client 3] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:29 +0000] [Client 3] Waiting for request.
D [28/Oct/2019:17:20:29 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Oct/2019:17:20:29 +0000] [Client 3] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:29 +0000] [Client 3] Closing connection.
D [28/Oct/2019:17:20:29 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:29 +0000] [Client 4] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:29 +0000] [Client 4] Waiting for request.
D [28/Oct/2019:17:20:29 +0000] [Client 4] POST / HTTP/1.1
D [28/Oct/2019:17:20:29 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:29 +0000] [Client 4] Read: status=200
D [28/Oct/2019:17:20:29 +0000] [Client 4] No authentication data provided.
D [28/Oct/2019:17:20:29 +0000] [Client 4] Read: status=100
D [28/Oct/2019:17:20:29 +0000] [Client 4] Read: status=100
D [28/Oct/2019:17:20:29 +0000] [Client 4] Read: status=100
D [28/Oct/2019:17:20:29 +0000] [Client 4] Read: status=100
D [28/Oct/2019:17:20:29 +0000] [Client 4] Read: status=100
D [28/Oct/2019:17:20:29 +0000] [Client 4] Read: status=100
D [28/Oct/2019:17:20:29 +0000] [Client 4] Read: status=100
D [28/Oct/2019:17:20:29 +0000] [Client 4] Read: status=100
D [28/Oct/2019:17:20:29 +0000] [Client 4] 2.0 CUPS-Get-Printers 3
D [28/Oct/2019:17:20:29 +0000] CUPS-Get-Printers
D [28/Oct/2019:17:20:29 +0000] [Client 4] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Oct/2019:17:20:29 +0000] [Client 4] Content-Length: 444
D [28/Oct/2019:17:20:29 +0000] [Client 4] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:29 +0000] [Client 4] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:29 +0000] [Client 4] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=444, response=0x56bbfbf8(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:29 +0000] [Client 4] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:29 +0000] [Client 4] bytes=0, http_state=0, data_remaining=444
D [28/Oct/2019:17:20:29 +0000] [Client 4] Flushing write buffer.
D [28/Oct/2019:17:20:29 +0000] [Client 4] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:29 +0000] [Client 4] Waiting for request.
D [28/Oct/2019:17:20:29 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Oct/2019:17:20:29 +0000] [Client 4] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:29 +0000] [Client 4] Closing connection.
D [28/Oct/2019:17:20:29 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:35 +0000] [Client 5] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:35 +0000] [Client 5] Waiting for request.
D [28/Oct/2019:17:20:35 +0000] [Client 5] POST / HTTP/1.1
D [28/Oct/2019:17:20:35 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:35 +0000] [Client 5] Read: status=200
D [28/Oct/2019:17:20:35 +0000] [Client 5] No authentication data provided.
D [28/Oct/2019:17:20:35 +0000] [Client 5] 2.0 CUPS-Get-Printers 1
D [28/Oct/2019:17:20:35 +0000] CUPS-Get-Printers
D [28/Oct/2019:17:20:35 +0000] [Client 5] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Oct/2019:17:20:35 +0000] [Client 5] Content-Length: 2006
D [28/Oct/2019:17:20:35 +0000] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:35 +0000] [Client 5] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:35 +0000] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2006, response=0x56bbf2b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:35 +0000] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:35 +0000] [Client 5] bytes=0, http_state=0, data_remaining=2006
D [28/Oct/2019:17:20:35 +0000] [Client 5] Flushing write buffer.
D [28/Oct/2019:17:20:35 +0000] [Client 5] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:35 +0000] [Client 5] Waiting for request.
D [28/Oct/2019:17:20:35 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Oct/2019:17:20:35 +0000] [Client 5] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:35 +0000] [Client 5] Closing connection.
D [28/Oct/2019:17:20:35 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:35 +0000] [Client 6] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:35 +0000] [Client 6] Waiting for request.
D [28/Oct/2019:17:20:35 +0000] [Client 6] POST / HTTP/1.1
D [28/Oct/2019:17:20:35 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:35 +0000] [Client 6] Read: status=200
D [28/Oct/2019:17:20:35 +0000] [Client 6] No authentication data provided.
D [28/Oct/2019:17:20:35 +0000] [Client 6] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 6] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 6] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 6] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 6] 2.0 CUPS-Get-Default 2
D [28/Oct/2019:17:20:35 +0000] CUPS-Get-Default
D [28/Oct/2019:17:20:35 +0000] CUPS-Get-Default client-error-not-found: No default printer.
D [28/Oct/2019:17:20:35 +0000] [Client 6] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [28/Oct/2019:17:20:35 +0000] [Client 6] Content-Length: 110
D [28/Oct/2019:17:20:35 +0000] [Client 6] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:35 +0000] [Client 6] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:35 +0000] [Client 6] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=110, response=0x56bbfbf8(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:35 +0000] [Client 6] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:35 +0000] [Client 6] bytes=0, http_state=0, data_remaining=110
D [28/Oct/2019:17:20:35 +0000] [Client 6] Flushing write buffer.
D [28/Oct/2019:17:20:35 +0000] [Client 6] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:35 +0000] [Client 6] Waiting for request.
D [28/Oct/2019:17:20:35 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Oct/2019:17:20:35 +0000] [Client 6] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:35 +0000] [Client 6] Closing connection.
D [28/Oct/2019:17:20:35 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:35 +0000] [Client 7] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:35 +0000] [Client 7] Waiting for request.
D [28/Oct/2019:17:20:35 +0000] [Client 7] POST / HTTP/1.1
D [28/Oct/2019:17:20:35 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:35 +0000] [Client 7] Read: status=200
D [28/Oct/2019:17:20:35 +0000] [Client 7] No authentication data provided.
D [28/Oct/2019:17:20:35 +0000] [Client 7] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 7] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 7] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 7] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 7] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 7] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 7] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 7] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 7] 2.0 CUPS-Get-Printers 3
D [28/Oct/2019:17:20:35 +0000] CUPS-Get-Printers
D [28/Oct/2019:17:20:35 +0000] [Client 7] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Oct/2019:17:20:35 +0000] [Client 7] Content-Length: 444
D [28/Oct/2019:17:20:35 +0000] [Client 7] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:35 +0000] [Client 7] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:35 +0000] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=444, response=0x56bbfbf8(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:35 +0000] [Client 7] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:35 +0000] [Client 7] bytes=0, http_state=0, data_remaining=444
D [28/Oct/2019:17:20:35 +0000] [Client 7] Flushing write buffer.
D [28/Oct/2019:17:20:35 +0000] [Client 7] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:35 +0000] [Client 7] Waiting for request.
D [28/Oct/2019:17:20:35 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Oct/2019:17:20:35 +0000] [Client 7] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:35 +0000] [Client 7] Closing connection.
D [28/Oct/2019:17:20:35 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:35 +0000] [Client 8] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:35 +0000] [Client 8] Waiting for request.
D [28/Oct/2019:17:20:35 +0000] [Client 8] POST / HTTP/1.1
D [28/Oct/2019:17:20:35 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:35 +0000] [Client 8] Read: status=200
D [28/Oct/2019:17:20:35 +0000] [Client 8] No authentication data provided.
D [28/Oct/2019:17:20:35 +0000] [Client 8] 2.0 CUPS-Get-Printers 1
D [28/Oct/2019:17:20:35 +0000] CUPS-Get-Printers
D [28/Oct/2019:17:20:35 +0000] [Client 8] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Oct/2019:17:20:35 +0000] [Client 8] Content-Length: 2006
D [28/Oct/2019:17:20:35 +0000] [Client 8] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:35 +0000] [Client 8] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:35 +0000] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2006, response=0x56bbf2b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:35 +0000] [Client 8] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:35 +0000] [Client 8] bytes=0, http_state=0, data_remaining=2006
D [28/Oct/2019:17:20:35 +0000] [Client 8] Flushing write buffer.
D [28/Oct/2019:17:20:35 +0000] [Client 8] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:35 +0000] [Client 8] Waiting for request.
D [28/Oct/2019:17:20:35 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Oct/2019:17:20:35 +0000] [Client 8] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:35 +0000] [Client 8] Closing connection.
D [28/Oct/2019:17:20:35 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:35 +0000] [Client 9] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:35 +0000] [Client 9] Waiting for request.
D [28/Oct/2019:17:20:35 +0000] [Client 9] POST / HTTP/1.1
D [28/Oct/2019:17:20:35 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:35 +0000] [Client 9] Read: status=200
D [28/Oct/2019:17:20:35 +0000] [Client 9] No authentication data provided.
D [28/Oct/2019:17:20:35 +0000] [Client 9] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 9] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 9] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 9] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 9] 2.0 CUPS-Get-Default 2
D [28/Oct/2019:17:20:35 +0000] CUPS-Get-Default
D [28/Oct/2019:17:20:35 +0000] CUPS-Get-Default client-error-not-found: No default printer.
D [28/Oct/2019:17:20:35 +0000] [Client 9] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [28/Oct/2019:17:20:35 +0000] [Client 9] Content-Length: 110
D [28/Oct/2019:17:20:35 +0000] [Client 9] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:35 +0000] [Client 9] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:35 +0000] [Client 9] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=110, response=0x56bbfbf8(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:35 +0000] [Client 9] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:35 +0000] [Client 9] bytes=0, http_state=0, data_remaining=110
D [28/Oct/2019:17:20:35 +0000] [Client 9] Flushing write buffer.
D [28/Oct/2019:17:20:35 +0000] [Client 9] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:35 +0000] [Client 9] Waiting for request.
D [28/Oct/2019:17:20:35 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Oct/2019:17:20:35 +0000] [Client 9] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:35 +0000] [Client 9] Closing connection.
D [28/Oct/2019:17:20:35 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:35 +0000] [Client 10] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:35 +0000] [Client 10] Waiting for request.
D [28/Oct/2019:17:20:35 +0000] [Client 10] POST / HTTP/1.1
D [28/Oct/2019:17:20:35 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:35 +0000] [Client 10] Read: status=200
D [28/Oct/2019:17:20:35 +0000] [Client 10] No authentication data provided.
D [28/Oct/2019:17:20:35 +0000] [Client 10] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 10] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 10] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 10] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 10] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 10] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 10] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 10] Read: status=100
D [28/Oct/2019:17:20:35 +0000] [Client 10] 2.0 CUPS-Get-Printers 3
D [28/Oct/2019:17:20:35 +0000] CUPS-Get-Printers
D [28/Oct/2019:17:20:35 +0000] [Client 10] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Oct/2019:17:20:35 +0000] [Client 10] Content-Length: 444
D [28/Oct/2019:17:20:35 +0000] [Client 10] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:35 +0000] [Client 10] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:35 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=444, response=0x56bbfbf8(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:35 +0000] [Client 10] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:35 +0000] [Client 10] bytes=0, http_state=0, data_remaining=444
D [28/Oct/2019:17:20:35 +0000] [Client 10] Flushing write buffer.
D [28/Oct/2019:17:20:35 +0000] [Client 10] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:35 +0000] [Client 10] Waiting for request.
D [28/Oct/2019:17:20:35 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Oct/2019:17:20:35 +0000] [Client 10] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:35 +0000] [Client 10] Closing connection.
D [28/Oct/2019:17:20:35 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:36 +0000] [Client 11] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:36 +0000] [Client 11] Waiting for request.
D [28/Oct/2019:17:20:36 +0000] [Client 11] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:36 +0000] [Client 11] Closing connection.
D [28/Oct/2019:17:20:36 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:37 +0000] [Client 12] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:37 +0000] [Client 12] Waiting for request.
D [28/Oct/2019:17:20:37 +0000] [Client 12] POST / HTTP/1.1
D [28/Oct/2019:17:20:37 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:37 +0000] [Client 12] Read: status=200
D [28/Oct/2019:17:20:37 +0000] [Client 12] No authentication data provided.
D [28/Oct/2019:17:20:37 +0000] [Client 12] 2.0 CUPS-Get-Printers 1
D [28/Oct/2019:17:20:37 +0000] CUPS-Get-Printers
D [28/Oct/2019:17:20:37 +0000] [Client 12] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Oct/2019:17:20:37 +0000] [Client 12] Content-Length: 2006
D [28/Oct/2019:17:20:37 +0000] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:37 +0000] [Client 12] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:37 +0000] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2006, response=0x56bbf2b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:37 +0000] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:37 +0000] [Client 12] bytes=0, http_state=0, data_remaining=2006
D [28/Oct/2019:17:20:37 +0000] [Client 12] Flushing write buffer.
D [28/Oct/2019:17:20:37 +0000] [Client 12] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:37 +0000] [Client 12] Waiting for request.
D [28/Oct/2019:17:20:37 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Oct/2019:17:20:37 +0000] [Client 12] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:37 +0000] [Client 12] Closing connection.
D [28/Oct/2019:17:20:37 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:37 +0000] [Client 13] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:37 +0000] [Client 13] Waiting for request.
D [28/Oct/2019:17:20:37 +0000] [Client 13] POST / HTTP/1.1
D [28/Oct/2019:17:20:37 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:37 +0000] [Client 13] Read: status=200
D [28/Oct/2019:17:20:37 +0000] [Client 13] No authentication data provided.
D [28/Oct/2019:17:20:37 +0000] [Client 13] Read: status=100
D [28/Oct/2019:17:20:37 +0000] [Client 13] Read: status=100
D [28/Oct/2019:17:20:37 +0000] [Client 13] Read: status=100
D [28/Oct/2019:17:20:37 +0000] [Client 13] Read: status=100
D [28/Oct/2019:17:20:37 +0000] [Client 13] 2.0 CUPS-Get-Default 2
D [28/Oct/2019:17:20:37 +0000] CUPS-Get-Default
D [28/Oct/2019:17:20:37 +0000] CUPS-Get-Default client-error-not-found: No default printer.
D [28/Oct/2019:17:20:37 +0000] [Client 13] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [28/Oct/2019:17:20:37 +0000] [Client 13] Content-Length: 110
D [28/Oct/2019:17:20:37 +0000] [Client 13] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:37 +0000] [Client 13] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:37 +0000] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=110, response=0x56bbfbf8(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:37 +0000] [Client 13] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:37 +0000] [Client 13] bytes=0, http_state=0, data_remaining=110
D [28/Oct/2019:17:20:37 +0000] [Client 13] Flushing write buffer.
D [28/Oct/2019:17:20:37 +0000] [Client 13] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:37 +0000] [Client 13] Waiting for request.
D [28/Oct/2019:17:20:37 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Oct/2019:17:20:37 +0000] [Client 13] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:37 +0000] [Client 13] Closing connection.
D [28/Oct/2019:17:20:37 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:37 +0000] [Client 14] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:37 +0000] [Client 14] Waiting for request.
D [28/Oct/2019:17:20:37 +0000] [Client 14] POST / HTTP/1.1
D [28/Oct/2019:17:20:37 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:37 +0000] [Client 14] Read: status=200
D [28/Oct/2019:17:20:37 +0000] [Client 14] No authentication data provided.
D [28/Oct/2019:17:20:37 +0000] [Client 14] Read: status=100
D [28/Oct/2019:17:20:37 +0000] [Client 14] Read: status=100
D [28/Oct/2019:17:20:37 +0000] [Client 14] Read: status=100
D [28/Oct/2019:17:20:37 +0000] [Client 14] Read: status=100
D [28/Oct/2019:17:20:37 +0000] [Client 14] Read: status=100
D [28/Oct/2019:17:20:37 +0000] [Client 14] Read: status=100
D [28/Oct/2019:17:20:37 +0000] [Client 14] Read: status=100
D [28/Oct/2019:17:20:37 +0000] [Client 14] Read: status=100
D [28/Oct/2019:17:20:37 +0000] [Client 14] 2.0 CUPS-Get-Printers 3
D [28/Oct/2019:17:20:37 +0000] CUPS-Get-Printers
D [28/Oct/2019:17:20:37 +0000] [Client 14] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Oct/2019:17:20:37 +0000] [Client 14] Content-Length: 444
D [28/Oct/2019:17:20:37 +0000] [Client 14] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:37 +0000] [Client 14] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:37 +0000] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=444, response=0x56bbfbf8(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:37 +0000] [Client 14] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:37 +0000] [Client 14] bytes=0, http_state=0, data_remaining=444
D [28/Oct/2019:17:20:37 +0000] [Client 14] Flushing write buffer.
D [28/Oct/2019:17:20:37 +0000] [Client 14] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:37 +0000] [Client 14] Waiting for request.
D [28/Oct/2019:17:20:37 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Oct/2019:17:20:37 +0000] [Client 14] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:37 +0000] [Client 14] Closing connection.
D [28/Oct/2019:17:20:37 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [28/Oct/2019:17:20:40 +0000] Saving printers.conf...
D [28/Oct/2019:17:20:41 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [28/Oct/2019:17:20:43 +0000] [Client 15] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:43 +0000] [Client 15] Waiting for request.
D [28/Oct/2019:17:20:43 +0000] [Client 15] POST / HTTP/1.1
D [28/Oct/2019:17:20:43 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=200
D [28/Oct/2019:17:20:43 +0000] [Client 15] No authentication data provided.
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 15] 2.0 CUPS-Get-Printers 1
D [28/Oct/2019:17:20:43 +0000] CUPS-Get-Printers
D [28/Oct/2019:17:20:43 +0000] [Client 15] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Oct/2019:17:20:43 +0000] [Client 15] Content-Length: 2006
D [28/Oct/2019:17:20:43 +0000] [Client 15] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:43 +0000] [Client 15] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:43 +0000] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2006, response=0x56bbf2b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:43 +0000] [Client 15] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:43 +0000] [Client 15] bytes=0, http_state=0, data_remaining=2006
D [28/Oct/2019:17:20:43 +0000] [Client 15] Flushing write buffer.
D [28/Oct/2019:17:20:43 +0000] [Client 15] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:43 +0000] [Client 15] Waiting for request.
D [28/Oct/2019:17:20:43 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Oct/2019:17:20:43 +0000] [Client 15] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:43 +0000] [Client 15] Closing connection.
D [28/Oct/2019:17:20:43 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Oct/2019:17:20:43 +0000] [Client 16] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:43 +0000] [Client 16] Waiting for request.
D [28/Oct/2019:17:20:43 +0000] [Client 16] POST / HTTP/1.1
D [28/Oct/2019:17:20:43 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Oct/2019:17:20:43 +0000] [Client 16] Read: status=200
D [28/Oct/2019:17:20:43 +0000] [Client 16] No authentication data provided.
D [28/Oct/2019:17:20:43 +0000] [Client 16] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 16] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 16] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 16] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 16] 2.0 CUPS-Get-Default 2
D [28/Oct/2019:17:20:43 +0000] CUPS-Get-Default
D [28/Oct/2019:17:20:43 +0000] CUPS-Get-Default client-error-not-found: No default printer.
D [28/Oct/2019:17:20:43 +0000] [Client 16] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [28/Oct/2019:17:20:43 +0000] [Client 16] Content-Length: 110
D [28/Oct/2019:17:20:43 +0000] [Client 16] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:43 +0000] [Client 16] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:43 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=110, response=0x56bbfbf8(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:43 +0000] [Client 16] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:43 +0000] [Client 16] bytes=0, http_state=0, data_remaining=110
D [28/Oct/2019:17:20:43 +0000] [Client 16] Flushing write buffer.
D [28/Oct/2019:17:20:43 +0000] [Client 16] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:43 +0000] [Client 16] Waiting for request.
D [28/Oct/2019:17:20:43 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Oct/2019:17:20:43 +0000] [Client 16] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:43 +0000] [Client 16] Closing connection.
D [28/Oct/2019:17:20:43 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Oct/2019:17:20:43 +0000] [Client 17] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:43 +0000] [Client 17] Waiting for request.
D [28/Oct/2019:17:20:43 +0000] [Client 17] POST / HTTP/1.1
D [28/Oct/2019:17:20:43 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Oct/2019:17:20:43 +0000] [Client 17] Read: status=200
D [28/Oct/2019:17:20:43 +0000] [Client 17] No authentication data provided.
D [28/Oct/2019:17:20:43 +0000] [Client 17] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 17] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 17] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 17] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 17] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 17] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 17] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 17] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 17] 2.0 CUPS-Get-Printers 3
D [28/Oct/2019:17:20:43 +0000] CUPS-Get-Printers
D [28/Oct/2019:17:20:43 +0000] [Client 17] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Oct/2019:17:20:43 +0000] [Client 17] Content-Length: 444
D [28/Oct/2019:17:20:43 +0000] [Client 17] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:43 +0000] [Client 17] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:43 +0000] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=444, response=0x56bbfbf8(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:43 +0000] [Client 17] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:43 +0000] [Client 17] bytes=0, http_state=0, data_remaining=444
D [28/Oct/2019:17:20:43 +0000] [Client 17] Flushing write buffer.
D [28/Oct/2019:17:20:43 +0000] [Client 17] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:43 +0000] [Client 17] Waiting for request.
D [28/Oct/2019:17:20:43 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Oct/2019:17:20:43 +0000] [Client 17] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:43 +0000] [Client 17] Closing connection.
D [28/Oct/2019:17:20:43 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Oct/2019:17:20:43 +0000] [Client 18] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:43 +0000] [Client 18] Waiting for request.
D [28/Oct/2019:17:20:43 +0000] [Client 18] POST / HTTP/1.1
D [28/Oct/2019:17:20:43 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=200
D [28/Oct/2019:17:20:43 +0000] [Client 18] No authentication data provided.
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 18] 2.0 CUPS-Get-Printers 1
D [28/Oct/2019:17:20:43 +0000] CUPS-Get-Printers
D [28/Oct/2019:17:20:43 +0000] [Client 18] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Oct/2019:17:20:43 +0000] [Client 18] Content-Length: 2006
D [28/Oct/2019:17:20:43 +0000] [Client 18] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:43 +0000] [Client 18] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:43 +0000] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2006, response=0x56bbf2b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:43 +0000] [Client 18] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:43 +0000] [Client 18] bytes=0, http_state=0, data_remaining=2006
D [28/Oct/2019:17:20:43 +0000] [Client 18] Flushing write buffer.
D [28/Oct/2019:17:20:43 +0000] [Client 18] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:43 +0000] [Client 18] Waiting for request.
D [28/Oct/2019:17:20:43 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Oct/2019:17:20:43 +0000] [Client 18] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:43 +0000] [Client 18] Closing connection.
D [28/Oct/2019:17:20:43 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Oct/2019:17:20:43 +0000] [Client 19] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:43 +0000] [Client 19] Waiting for request.
D [28/Oct/2019:17:20:43 +0000] [Client 19] POST / HTTP/1.1
D [28/Oct/2019:17:20:43 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Oct/2019:17:20:43 +0000] [Client 19] Read: status=200
D [28/Oct/2019:17:20:43 +0000] [Client 19] No authentication data provided.
D [28/Oct/2019:17:20:43 +0000] [Client 19] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 19] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 19] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 19] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 19] 2.0 CUPS-Get-Default 2
D [28/Oct/2019:17:20:43 +0000] CUPS-Get-Default
D [28/Oct/2019:17:20:43 +0000] CUPS-Get-Default client-error-not-found: No default printer.
D [28/Oct/2019:17:20:43 +0000] [Client 19] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [28/Oct/2019:17:20:43 +0000] [Client 19] Content-Length: 110
D [28/Oct/2019:17:20:43 +0000] [Client 19] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:43 +0000] [Client 19] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:43 +0000] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=110, response=0x56bbfbf8(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:43 +0000] [Client 19] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:43 +0000] [Client 19] bytes=0, http_state=0, data_remaining=110
D [28/Oct/2019:17:20:43 +0000] [Client 19] Flushing write buffer.
D [28/Oct/2019:17:20:43 +0000] [Client 19] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:43 +0000] [Client 19] Waiting for request.
D [28/Oct/2019:17:20:43 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Oct/2019:17:20:43 +0000] [Client 19] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:43 +0000] [Client 19] Closing connection.
D [28/Oct/2019:17:20:43 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Oct/2019:17:20:43 +0000] [Client 20] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:43 +0000] [Client 20] Waiting for request.
D [28/Oct/2019:17:20:43 +0000] [Client 20] POST / HTTP/1.1
D [28/Oct/2019:17:20:43 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Oct/2019:17:20:43 +0000] [Client 20] Read: status=200
D [28/Oct/2019:17:20:43 +0000] [Client 20] No authentication data provided.
D [28/Oct/2019:17:20:43 +0000] [Client 20] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 20] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 20] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 20] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 20] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 20] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 20] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 20] Read: status=100
D [28/Oct/2019:17:20:43 +0000] [Client 20] 2.0 CUPS-Get-Printers 3
D [28/Oct/2019:17:20:43 +0000] CUPS-Get-Printers
D [28/Oct/2019:17:20:43 +0000] [Client 20] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Oct/2019:17:20:43 +0000] [Client 20] Content-Length: 444
D [28/Oct/2019:17:20:43 +0000] [Client 20] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:43 +0000] [Client 20] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:43 +0000] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=444, response=0x56bbfbf8(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:43 +0000] [Client 20] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:43 +0000] [Client 20] bytes=0, http_state=0, data_remaining=444
D [28/Oct/2019:17:20:43 +0000] [Client 20] Flushing write buffer.
D [28/Oct/2019:17:20:43 +0000] [Client 20] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:43 +0000] [Client 20] Waiting for request.
D [28/Oct/2019:17:20:43 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Oct/2019:17:20:43 +0000] [Client 20] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:43 +0000] [Client 20] Closing connection.
D [28/Oct/2019:17:20:43 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Oct/2019:17:20:54 +0000] [Client 21] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:54 +0000] [Client 21] Waiting for request.
D [28/Oct/2019:17:20:54 +0000] [Client 21] POST / HTTP/1.1
D [28/Oct/2019:17:20:54 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Oct/2019:17:20:54 +0000] [Client 21] Read: status=200
D [28/Oct/2019:17:20:54 +0000] [Client 21] No authentication data provided.
D [28/Oct/2019:17:20:54 +0000] [Client 21] 2.0 Get-Printer-Attributes 1
D [28/Oct/2019:17:20:54 +0000] Get-Printer-Attributes ipp://localhost:631/printers/HP_LaserJet_M1536dnf_MFP
D [28/Oct/2019:17:20:54 +0000] [Client 21] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/HP_LaserJet_M1536dnf_MFP) from localhost
D [28/Oct/2019:17:20:54 +0000] [Client 21] Content-Length: 871
D [28/Oct/2019:17:20:54 +0000] [Client 21] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:54 +0000] [Client 21] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:54 +0000] [Client 21] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=871, response=0x56bbf2e8(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:54 +0000] [Client 21] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:54 +0000] [Client 21] bytes=0, http_state=0, data_remaining=871
D [28/Oct/2019:17:20:54 +0000] [Client 21] Flushing write buffer.
D [28/Oct/2019:17:20:54 +0000] [Client 21] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:54 +0000] [Client 21] Waiting for request.
D [28/Oct/2019:17:20:54 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Oct/2019:17:20:54 +0000] [Client 21] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:54 +0000] [Client 21] Closing connection.
D [28/Oct/2019:17:20:54 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Oct/2019:17:20:54 +0000] [Client 22] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:54 +0000] [Client 22] Waiting for request.
D [28/Oct/2019:17:20:54 +0000] [Client 22] POST /printers/HP_LaserJet_M1536dnf_MFP HTTP/1.1
D [28/Oct/2019:17:20:54 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Oct/2019:17:20:54 +0000] [Client 22] Read: status=200
D [28/Oct/2019:17:20:54 +0000] [Client 22] No authentication data provided.
D [28/Oct/2019:17:20:54 +0000] [Client 22] 2.0 Create-Job 2
D [28/Oct/2019:17:20:54 +0000] Create-Job ipp://localhost:631/printers/HP_LaserJet_M1536dnf_MFP
D [28/Oct/2019:17:20:54 +0000] cupsdMarkDirty(---J-)
D [28/Oct/2019:17:20:54 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [28/Oct/2019:17:20:54 +0000] add_job: requesting-user-name="root"
I [28/Oct/2019:17:20:54 +0000] [Job 8] Adding start banner page "none".
D [28/Oct/2019:17:20:54 +0000] Discarding unused job-created event...
I [28/Oct/2019:17:20:54 +0000] [Job 8] Queued on "HP_LaserJet_M1536dnf_MFP" by "root".
D [28/Oct/2019:17:20:54 +0000] [Client 22] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/HP_LaserJet_M1536dnf_MFP) from localhost
D [28/Oct/2019:17:20:54 +0000] [Client 22] Content-Length: 199
D [28/Oct/2019:17:20:54 +0000] [Client 22] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:54 +0000] [Client 22] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:54 +0000] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=199, response=0x56bbee60(IPP_STATE_IDLE), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:54 +0000] [Client 22] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:54 +0000] [Client 22] bytes=0, http_state=0, data_remaining=199
D [28/Oct/2019:17:20:54 +0000] [Client 22] Flushing write buffer.
D [28/Oct/2019:17:20:54 +0000] [Client 22] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:54 +0000] [Client 22] Waiting for request.
D [28/Oct/2019:17:20:54 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Oct/2019:17:20:54 +0000] [Client 22] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:54 +0000] [Client 22] Closing connection.
D [28/Oct/2019:17:20:54 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:54 +0000] [Client 23] Accepted from localhost (Domain)
D [28/Oct/2019:17:20:54 +0000] [Client 23] Waiting for request.
D [28/Oct/2019:17:20:54 +0000] [Client 23] POST /printers/HP_LaserJet_M1536dnf_MFP HTTP/1.1
D [28/Oct/2019:17:20:54 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Oct/2019:17:20:54 +0000] [Client 23] Read: status=200
D [28/Oct/2019:17:20:54 +0000] [Client 23] No authentication data provided.
D [28/Oct/2019:17:20:54 +0000] [Client 23] 2.0 Send-Document 3
D [28/Oct/2019:17:20:54 +0000] [Client 23] Read: status=100
D [28/Oct/2019:17:20:54 +0000] [Client 23] Read: status=100
D [28/Oct/2019:17:20:54 +0000] Send-Document ipp://localhost:631/printers/HP_LaserJet_M1536dnf_MFP
D [28/Oct/2019:17:20:54 +0000] cupsdIsAuthorized: requesting-user-name="root"
D [28/Oct/2019:17:20:54 +0000] [Job 8] Auto-typing file...
D [28/Oct/2019:17:20:54 +0000] [Job 8] Request file type is application/pdf.
D [28/Oct/2019:17:20:54 +0000] cupsdMarkDirty(---J-)
D [28/Oct/2019:17:20:54 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [28/Oct/2019:17:20:54 +0000] [Job 8] File of type application/pdf queued by "root".
I [28/Oct/2019:17:20:54 +0000] [Job 8] Adding end banner page "none".
D [28/Oct/2019:17:20:54 +0000] cupsdMarkDirty(---J-)
D [28/Oct/2019:17:20:54 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [28/Oct/2019:17:20:54 +0000] [Job 8] time-at-processing=1572283254
D [28/Oct/2019:17:20:54 +0000] cupsdMarkDirty(---J-)
D [28/Oct/2019:17:20:54 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [28/Oct/2019:17:20:54 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [28/Oct/2019:17:20:54 +0000] Discarding unused printer-state-changed event...
D [28/Oct/2019:17:20:54 +0000] [Job 8] Sending job to queue tagged as raw...
D [28/Oct/2019:17:20:54 +0000] [Job 8] job-sheets=none,none
D [28/Oct/2019:17:20:54 +0000] [Job 8] argv[0]="HP_LaserJet_M1536dnf_MFP"
D [28/Oct/2019:17:20:54 +0000] [Job 8] argv[1]="8"
D [28/Oct/2019:17:20:54 +0000] [Job 8] argv[2]="root"
D [28/Oct/2019:17:20:54 +0000] [Job 8] argv[3]="m6168(8)(1)(2).pdf"
D [28/Oct/2019:17:20:54 +0000] [Job 8] argv[4]="1"
D [28/Oct/2019:17:20:54 +0000] [Job 8] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:1a1cba61-1302-3e38-6e8e-21389d890a02 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1572283254 time-at-processing=1572283254 document-name-supplied=m6168(8)(1)(2).pdf"
D [28/Oct/2019:17:20:54 +0000] [Job 8] argv[6]="/var/spool/cups/d00008-001"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[2]="CUPS_DOCROOT=/usr/share/doc/cups"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[8]="HOME=/var/spool/cups/tmp"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[10]="SERVER_ADMIN=root at imx6qsabresd"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[11]="SOFTWARE=CUPS/2.1.3"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[13]="USER=root"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[14]="CUPS_MAX_MESSAGE=2047"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[17]="IPP_PORT=631"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[18]="CHARSET=utf-8"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[19]="LANG=en_US.UTF-8"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[20]="PPD=/etc/cups/ppd/HP_LaserJet_M1536dnf_MFP.ppd"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[21]="RIP_MAX_CACHE=128m"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[22]="CONTENT_TYPE=application/pdf"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[23]="DEVICE_URI=hp:/usb/HP_LaserJet_M1536dnf_MFP?serial=00CND9D3MCD2"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[24]="PRINTER_INFO=HP_LaserJet_M1536dnf_MFP"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[25]="PRINTER_LOCATION=HP_LaserJet_M1536dnf_MFP"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[26]="PRINTER=HP_LaserJet_M1536dnf_MFP"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[27]="PRINTER_STATE_REASONS=none"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[28]="CUPS_FILETYPE=document"
D [28/Oct/2019:17:20:54 +0000] [Job 8] envp[29]="AUTH_I****"
I [28/Oct/2019:17:20:54 +0000] [Job 8] Started backend /usr/lib/cups/backend/hp (PID 924)
D [28/Oct/2019:17:20:54 +0000] Discarding unused job-state-changed event...
D [28/Oct/2019:17:20:54 +0000] [Client 23] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/HP_LaserJet_M1536dnf_MFP) from localhost
D [28/Oct/2019:17:20:54 +0000] [Client 23] Content-Length: 169
D [28/Oct/2019:17:20:54 +0000] [Client 23] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Oct/2019:17:20:54 +0000] [Client 23] con->http=0x56c4e2b0
D [28/Oct/2019:17:20:54 +0000] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=169, response=0x56c4d0e8(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Oct/2019:17:20:54 +0000] [Client 23] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Oct/2019:17:20:54 +0000] [Client 23] bytes=0, http_state=0, data_remaining=169
D [28/Oct/2019:17:20:54 +0000] [Client 23] Flushing write buffer.
D [28/Oct/2019:17:20:54 +0000] [Client 23] New state is HTTP_STATE_WAITING
D [28/Oct/2019:17:20:54 +0000] [Client 23] Waiting for request.
D [28/Oct/2019:17:20:54 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients and dirty files"
D [28/Oct/2019:17:20:54 +0000] [Client 23] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Oct/2019:17:20:54 +0000] [Client 23] Closing connection.
D [28/Oct/2019:17:20:54 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Oct/2019:17:20:54 +0000] [Job 8] hp:/usb/HP_LaserJet_M1536dnf_MFP?serial=00CND9D3MCD2: symbol lookup error: hp:/usb/HP_LaserJet_M1536dnf_MFP?serial=00CND9D3MCD2: undefined symbol: getHPLogLevel
D [28/Oct/2019:17:20:54 +0000] [Job 8] PID 924 (/usr/lib/cups/backend/hp) stopped with status 127 (File too large)
I [28/Oct/2019:17:20:54 +0000] [Job 8] Backend returned status 127 (unknown)
D [28/Oct/2019:17:20:54 +0000] Discarding unused job-state-changed event...
I [28/Oct/2019:17:20:54 +0000] [Job 8] Printer stopped due to backend errors; please consult the error_log file for details.
D [28/Oct/2019:17:20:54 +0000] cupsdMarkDirty(---J-)
D [28/Oct/2019:17:20:54 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Oct/2019:17:20:54 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Oct/2019:17:20:54 +0000] Discarding unused printer-stopped event...
D [28/Oct/2019:17:20:54 +0000] cupsdMarkDirty(P----)
D [28/Oct/2019:17:20:54 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Oct/2019:17:20:54 +0000] cupsdRegisterPrinter(p=0x56bd03d0(HP_LaserJet_M1536dnf_MFP))
D [28/Oct/2019:17:20:54 +0000] cupsdMarkDirty(P----)
D [28/Oct/2019:17:20:54 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Oct/2019:17:20:54 +0000] cupsdMarkDirty(---J-)
D [28/Oct/2019:17:20:54 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Oct/2019:17:20:54 +0000] cupsdMarkDirty(---J-)
D [28/Oct/2019:17:20:54 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Oct/2019:17:21:17 +0000] Report: clients=0
D [28/Oct/2019:17:21:17 +0000] Report: jobs=1
D [28/Oct/2019:17:21:17 +0000] Report: jobs-active=1
D [28/Oct/2019:17:21:17 +0000] Report: printers=2
D [28/Oct/2019:17:21:17 +0000] Report: stringpool-string-count=7920
D [28/Oct/2019:17:21:17 +0000] Report: stringpool-alloc-bytes=11432
D [28/Oct/2019:17:21:17 +0000] Report: stringpool-total-bytes=144096
I [28/Oct/2019:17:21:25 +0000] Saving printers.conf...
I [28/Oct/2019:17:21:25 +0000] Saving job.cache...
D [28/Oct/2019:17:21:25 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Printing jobs and dirty files"
D [28/Oct/2019:17:22:20 +0000] Report: clients=0
D [28/Oct/2019:17:22:20 +0000] Report: jobs=1
D [28/Oct/2019:17:22:20 +0000] Report: jobs-active=1
D [28/Oct/2019:17:22:20 +0000] Report: printers=2
D [28/Oct/2019:17:22:20 +0000] Report: stringpool-string-count=7920
D [28/Oct/2019:17:22:20 +0000] Report: stringpool-alloc-bytes=11432
D [28/Oct/2019:17:22:20 +0000] Report: stringpool-total-bytes=144096
D [28/Oct/2019:17:23:27 +0000] Report: clients=0
D [28/Oct/2019:17:23:27 +0000] Report: jobs=1
D [28/Oct/2019:17:23:27 +0000] Report: jobs-active=1
D [28/Oct/2019:17:23:27 +0000] Report: printers=2
D [28/Oct/2019:17:23:27 +0000] Report: stringpool-string-count=7920
D [28/Oct/2019:17:23:27 +0000] Report: stringpool-alloc-bytes=11432






Thank you