[cups] "Filter failed."-error with Brother HL-1110 and Raspbian Jessie

Sat Jun 17 07:32:19 PDT 2017

Hi,

I’m trying to get a Brother printer HL-1110 running on a Raspberry Pi (Raspbian Jessie is installed). Brother offers a CUPSwrapper (which entails the PPD file and a filter file) on their website for this specific printer and I followed exactly these steps of another user (in german: http://blog.loco-toys.de/wordpress/2014/06/brother-hl-1110-treiber-unter-linuxcupsraspian/ <http://blog.loco-toys.de/wordpress/2014/06/brother-hl-1110-treiber-unter-linuxcupsraspian/>), who managed to get his printer running. I first installed cups on the Raspberry Pi and then installed the printer via localhost:631. During the installation process, I selected the specific PPD file which I downloaded from the Brother website and copied the filter file to the folder /usr/lib/cups/filter/. Each time when I start a test print, the job immediately stops with stating: Stopped. „Filter failed.“ - does anyone have an idea what to do?
Attached is the error log (debug mode). I hope someone can help, am spending almost 2 days already with this and I have no idea what to do.

Best,

Julian

Error Log:

D [17/Jun/2017:15:41:44 +0200] Discarding unused server-started event...
D [17/Jun/2017:15:41:44 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:41:45 +0200] Report: clients=0
D [17/Jun/2017:15:41:45 +0200] Report: jobs=8
D [17/Jun/2017:15:41:45 +0200] Report: jobs-active=1
D [17/Jun/2017:15:41:45 +0200] Report: printers=1
D [17/Jun/2017:15:41:45 +0200] Report: stringpool-string-count=450
D [17/Jun/2017:15:41:45 +0200] Report: stringpool-alloc-bytes=6960
D [17/Jun/2017:15:41:45 +0200] Report: stringpool-total-bytes=8248
D [17/Jun/2017:15:41:45 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:00 +0200] [Client 14] Accepted from localhost:631 (IPv6)
D [17/Jun/2017:15:42:00 +0200] [Client 14] Waiting for request.
D [17/Jun/2017:15:42:00 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:00 +0200] [Client 14] POST /jobs/ HTTP/1.1
D [17/Jun/2017:15:42:00 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [17/Jun/2017:15:42:01 +0200] [Client 14] Authorized as pi using Basic
D [17/Jun/2017:15:42:01 +0200] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/jobs.cgi"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[10] = "SERVER_ADMIN=root at raspberrypi"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[11] = "SOFTWARE=CUPS/1.7.5"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[13] = "USER=root"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[15] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[17] = "IPP_PORT=631"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[18] = "AUTH_TYPE=Basic"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[19] = "LANG=en_GB.UTF8"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[22] = "SERVER_NAME=localhost"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[23] = "SERVER_PORT=631"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[24] = "REMOTE_ADDR=[v1.::1]"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[25] = "REMOTE_HOST=localhost"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[26] = "SCRIPT_NAME=/jobs/"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/jobs/"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[28] = "REMOTE_USER=pi"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[30] = "HTTP_COOKIE=org.cups.sid=6f7d3387005105a9caf7a11344736d46"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux armv6l) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/56.0.2924.84 Safari/537.36"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[32] = "HTTP_REFERER=http://localhost:631/printers/Brother_HL-1110_series"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[33] = "REQUEST_METHOD=POST"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[34] = "CONTENT_LENGTH=122"
D [17/Jun/2017:15:42:01 +0200] [CGI] envp[35] = "CONTENT_TYPE=application/x-www-form-urlencoded"
D [17/Jun/2017:15:42:01 +0200] [CGI] Started /usr/lib/cups/cgi-bin/jobs.cgi (PID 6995)
I [17/Jun/2017:15:42:01 +0200] [Client 14] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=6995)
D [17/Jun/2017:15:42:01 +0200] [Client 14] file=16
D [17/Jun/2017:15:42:01 +0200] [Client 14] Waiting for CGI data.
D [17/Jun/2017:15:42:01 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:01 +0200] [CGI] org.cups.sid cookie is "6f7d3387005105a9caf7a11344736d46"
D [17/Jun/2017:15:42:01 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:01 +0200] [CGI] cgiSetVariable: org.cups.sid="6f7d3387005105a9caf7a11344736d46"
D [17/Jun/2017:15:42:01 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:02 +0200] [CGI] cgiSetVariable: OP="restart-job"
D [17/Jun/2017:15:42:02 +0200] [CGI] cgiSetVariable: job_id="8"
D [17/Jun/2017:15:42:02 +0200] [CGI] cgiSetVariable: job_printer_uri="/printers/Brother_HL-1110_series"
D [17/Jun/2017:15:42:02 +0200] [CGI] cgiSetVariable: SECTION="jobs"
D [17/Jun/2017:15:42:02 +0200] [CGI] cgiSetVariable: REFRESH_PAGE=""
D [17/Jun/2017:15:42:02 +0200] [Client 15] Accepted from localhost (Domain)
D [17/Jun/2017:15:42:02 +0200] [Client 15] Waiting for request.
D [17/Jun/2017:15:42:02 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:02 +0200] [Client 15] POST /jobs HTTP/1.1
D [17/Jun/2017:15:42:02 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [17/Jun/2017:15:42:02 +0200] [Client 15] No authentication data provided.
D [17/Jun/2017:15:42:02 +0200] [Client 15] 2.0 Restart-Job 1
D [17/Jun/2017:15:42:02 +0200] Restart-Job ipp://localhost/jobs/8
D [17/Jun/2017:15:42:02 +0200] cupsdIsAuthorized: requesting-user-name="pi"
D [17/Jun/2017:15:42:02 +0200] Discarding unused job-state-changed event...
I [17/Jun/2017:15:42:02 +0200] [Job 8] Job restarted by user.
D [17/Jun/2017:15:42:02 +0200] cupsdMarkDirty(---J-)
D [17/Jun/2017:15:42:02 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [17/Jun/2017:15:42:02 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [17/Jun/2017:15:42:02 +0200] [Job 8] time-at-processing=1497706922
D [17/Jun/2017:15:42:02 +0200] cupsdMarkDirty(---J-)
D [17/Jun/2017:15:42:02 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [17/Jun/2017:15:42:02 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [17/Jun/2017:15:42:02 +0200] Discarding unused printer-state-changed event...
D [17/Jun/2017:15:42:02 +0200] [Job 8] 4 filters for job:
D [17/Jun/2017:15:42:02 +0200] [Job 8] bannertopdf (application/vnd.cups-pdf-banner to application/pdf, cost 32)
D [17/Jun/2017:15:42:02 +0200] [Job 8] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)
D [17/Jun/2017:15:42:02 +0200] [Job 8] pdftops (application/vnd.cups-pdf to application/vnd.cups-postscript, cost 100)
D [17/Jun/2017:15:42:02 +0200] [Job 8] /usr/lib/cups/filter/brother_lpdwrapper_HL1110 (application/vnd.cups-postscript to printer/Brother_HL-1110_series, cost 0)
D [17/Jun/2017:15:42:02 +0200] [Job 8] job-sheets=none,none
D [17/Jun/2017:15:42:02 +0200] [Job 8] argv[0]="Brother_HL-1110_series"
D [17/Jun/2017:15:42:02 +0200] [Job 8] argv[1]="8"
D [17/Jun/2017:15:42:02 +0200] [Job 8] argv[2]="pi"
D [17/Jun/2017:15:42:02 +0200] [Job 8] argv[3]="Test Page"
D [17/Jun/2017:15:42:02 +0200] [Job 8] argv[4]="1"
D [17/Jun/2017:15:42:02 +0200] [Job 8] argv[5]="job-uuid=urn:uuid:ce0f172f-f1dd-3f93-4bce-0229d86b6e12 job-originating-host-name=localhost time-at-creation=1497706130 time-at-processing=1497706922"
D [17/Jun/2017:15:42:02 +0200] [Job 8] argv[6]="/var/spool/cups/d00008-001"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[8]="HOME=/var/spool/cups/tmp"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[10]="SERVER_ADMIN=root at raspberrypi"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[11]="SOFTWARE=CUPS/1.7.5"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[13]="USER=root"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[14]="CUPS_MAX_MESSAGE=2047"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[17]="IPP_PORT=631"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[18]="CHARSET=utf-8"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[19]="LANG=en_GB.UTF-8"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[20]="PPD=/etc/cups/ppd/Brother_HL-1110_series.ppd"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[21]="RIP_MAX_CACHE=128m"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[22]="CONTENT_TYPE=application/vnd.cups-pdf-banner"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[23]="DEVICE_URI=usb://Brother/HL-1110%20series?serial=K3N503736"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[24]="PRINTER_INFO=Brother HL-1110 series"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[25]="PRINTER_LOCATION="
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[26]="PRINTER=Brother_HL-1110_series"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[27]="PRINTER_STATE_REASONS=none"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[28]="CUPS_FILETYPE=document"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
D [17/Jun/2017:15:42:02 +0200] [Job 8] envp[30]="AUTH_I****"
I [17/Jun/2017:15:42:02 +0200] [Job 8] Started filter /usr/lib/cups/filter/bannertopdf (PID 6996)
I [17/Jun/2017:15:42:02 +0200] [Job 8] Started filter /usr/lib/cups/filter/pdftopdf (PID 6997)
I [17/Jun/2017:15:42:02 +0200] [Job 8] Started filter /usr/lib/cups/filter/pdftops (PID 6998)
I [17/Jun/2017:15:42:02 +0200] [Job 8] Started filter /usr/lib/cups/filter/brother_lpdwrapper_HL1110 (PID 6999)
I [17/Jun/2017:15:42:02 +0200] [Job 8] Started backend /usr/lib/cups/backend/usb (PID 7000)
D [17/Jun/2017:15:42:02 +0200] Discarding unused job-state-changed event...
I [17/Jun/2017:15:42:02 +0200] [Job 8] Restarted by "pi".
D [17/Jun/2017:15:42:02 +0200] [Client 15] Returning IPP successful-ok for Restart-Job (ipp://localhost/jobs/8) from localhost
D [17/Jun/2017:15:42:02 +0200] [Client 15] Content-Length: 75
D [17/Jun/2017:15:42:02 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:02 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x80e18358(IPP_IDLE), pipe_pid=0, file=-1
D [17/Jun/2017:15:42:02 +0200] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [17/Jun/2017:15:42:02 +0200] [Client 15] bytes=0, http_state=0, data_remaining=0
D [17/Jun/2017:15:42:02 +0200] [Client 15] Waiting for request.
D [17/Jun/2017:15:42:02 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
D [17/Jun/2017:15:42:02 +0200] PID 6995 (/usr/lib/cups/cgi-bin/jobs.cgi) exited with no errors.
D [17/Jun/2017:15:42:02 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:02 +0200] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [17/Jun/2017:15:42:02 +0200] [Client 15] Closing connection.
D [17/Jun/2017:15:42:02 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/Jun/2017:15:42:02 +0200] [CGI] cgiSetVariable: refresh_page="5;URL=http://localhost:631/printers/Brother_HL-1110_series"
D [17/Jun/2017:15:42:02 +0200] [CGI] cgiSetVariable: TITLE="Jobs"
D [17/Jun/2017:15:42:02 +0200] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [17/Jun/2017:15:42:02 +0200] [CGI] cgiSetVariable: REMOTE_USER="pi"
D [17/Jun/2017:15:42:02 +0200] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.5"
D [17/Jun/2017:15:42:02 +0200] [Client 14] CGI data ready to be sent.
D [17/Jun/2017:15:42:02 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:02 +0200] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, response=(nil)(), pipe_pid=6995, file=16
D [17/Jun/2017:15:42:02 +0200] [Client 14] Waiting for CGI data.
D [17/Jun/2017:15:42:02 +0200] Script header: Content-Type: text/html;charset=utf-8
D [17/Jun/2017:15:42:02 +0200] Script header: 
D [17/Jun/2017:15:42:02 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:02 +0200] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=6995, file=16
D [17/Jun/2017:15:42:02 +0200] [Client 14] Waiting for CGI data.
D [17/Jun/2017:15:42:02 +0200] [Client 14] CGI data ready to be sent.
D [17/Jun/2017:15:42:02 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:02 +0200] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=6995, file=16
D [17/Jun/2017:15:42:02 +0200] [Client 14] Waiting for CGI data.
D [17/Jun/2017:15:42:02 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:02 +0200] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=6995, file=16
D [17/Jun/2017:15:42:02 +0200] [Client 14] Waiting for CGI data.
D [17/Jun/2017:15:42:02 +0200] [Client 14] CGI data ready to be sent.
D [17/Jun/2017:15:42:02 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:02 +0200] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=6995, file=16
D [17/Jun/2017:15:42:02 +0200] [Client 14] Waiting for CGI data.
D [17/Jun/2017:15:42:02 +0200] [Client 14] Waiting for request.
D [17/Jun/2017:15:42:02 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [17/Jun/2017:15:42:02 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:03 +0200] [Job 8] pdftops - copying to temp print file "/var/spool/cups/tmp/01b5659490052"
D [17/Jun/2017:15:42:03 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:03 +0200] [Job 8] Loading USB quirks from "/usr/share/cups/usb".
D [17/Jun/2017:15:42:03 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:03 +0200] [Job 8] Loaded 113 quirks.
D [17/Jun/2017:15:42:03 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:03 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:03 +0200] [Job 8] cp: DEBUG: Printing on printer with URI: usb://Brother/HL-1110%20series?serial=K3N503736
D [17/Jun/2017:15:42:03 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:03 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:03 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:03 +0200] [Job 8] cannot stat ‘/opt/brother/Printers/HL1110//inf/brHL1110rc’: No such file or directory
D [17/Jun/2017:15:42:03 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:03 +0200] [Job 8] libusb_get_device_list=7
D [17/Jun/2017:15:42:03 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:03 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:03 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:03 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:03 +0200] [Job 8] rm: cannot remove ‘/tmp/brHL1110rc_6999’: No such file or directory
D [17/Jun/2017:15:42:03 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:03 +0200] [Job 8] PID 6999 (/usr/lib/cups/filter/brother_lpdwrapper_HL1110) stopped with status 1.
D [17/Jun/2017:15:42:03 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:03 +0200] [Job 8] STATE: +connecting-to-device
D [17/Jun/2017:15:42:03 +0200] cupsdMarkDirty(---J-)
D [17/Jun/2017:15:42:03 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
D [17/Jun/2017:15:42:03 +0200] Discarding unused printer-state-changed event...
D [17/Jun/2017:15:42:03 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:03 +0200] [Job 8] STATE: -connecting-to-device
D [17/Jun/2017:15:42:03 +0200] cupsdMarkDirty(---J-)
D [17/Jun/2017:15:42:03 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
D [17/Jun/2017:15:42:03 +0200] Discarding unused printer-state-changed event...
D [17/Jun/2017:15:42:03 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:03 +0200] [Job 8] Device protocol: 2
I [17/Jun/2017:15:42:03 +0200] [Job 8] Sending data to printer.
D [17/Jun/2017:15:42:03 +0200] [Job 8] Sent 0 bytes...
D [17/Jun/2017:15:42:03 +0200] [Job 8] Waiting for read thread to exit...
D [17/Jun/2017:15:42:03 +0200] Discarding unused job-progress event...
D [17/Jun/2017:15:42:03 +0200] Discarding unused printer-state-changed event...
D [17/Jun/2017:15:42:03 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:03 +0200] [Job 8] PDF template file doesn't have form. It's okay.
D [17/Jun/2017:15:42:03 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:03 +0200] [Job 8] PID 7000 (/usr/lib/cups/backend/usb) exited with no errors.
D [17/Jun/2017:15:42:03 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:04 +0200] [Job 8] PID 6996 (/usr/lib/cups/filter/bannertopdf) exited with no errors.
D [17/Jun/2017:15:42:04 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:04 +0200] [Job 8] Printer make and model: Brother Brother HL-1110 series
D [17/Jun/2017:15:42:04 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:04 +0200] [Job 8] PID 6997 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [17/Jun/2017:15:42:04 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:04 +0200] [Job 8] Switching to Poppler's pdftops instead of Ghostscript for Brother, Minolta, and Konica Minolta to work around bugs in the printer's PS interpreters
D [17/Jun/2017:15:42:04 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:04 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:04 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:04 +0200] [Job 8] Running command line for pstops: pstops 8 pi 'Test Page' 1 'job-uuid=urn:uuid:ce0f172f-f1dd-3f93-4bce-0229d86b6e12 job-originating-host-name=localhost time-at-creation=1497706130 time-at-processing=1497706922'
D [17/Jun/2017:15:42:04 +0200] [Job 8] Using image rendering resolution 300 dpi
D [17/Jun/2017:15:42:04 +0200] [Job 8] Running command line for pdftops: pdftops -level3 -origpagesizes -nocenter -r 300 /var/spool/cups/tmp/01b5659490052 -
D [17/Jun/2017:15:42:04 +0200] [Job 8] Started filter pdftops (PID 7023)
D [17/Jun/2017:15:42:04 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:04 +0200] [Job 8] Started filter pstops (PID 7024)
D [17/Jun/2017:15:42:04 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:04 +0200] [Job 8] Page = 595x842; 12,12 to 583,830
D [17/Jun/2017:15:42:04 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:04 +0200] [Job 8] slow_collate=0, slow_duplex=0, slow_order=0
D [17/Jun/2017:15:42:04 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:04 +0200] [Job 8] Before copy_comments - %!PS-Adobe-3.0
D [17/Jun/2017:15:42:04 +0200] [Job 8] %!PS-Adobe-3.0
D [17/Jun/2017:15:42:04 +0200] [Job 8] %Produced by poppler pdftops version: 0.26.5 (http://poppler.freedesktop.org)
D [17/Jun/2017:15:42:04 +0200] [Job 8] %%Creator: cairo 1.14.0 (http://cairographics.org)
D [17/Jun/2017:15:42:04 +0200] [Job 8] %%LanguageLevel: 3
D [17/Jun/2017:15:42:04 +0200] [Job 8] %%DocumentSuppliedResources: (atend)
D [17/Jun/2017:15:42:04 +0200] [Job 8] %%DocumentMedia: A4 595 842 0 () ()
D [17/Jun/2017:15:42:04 +0200] [Job 8] %%BoundingBox: 0 0 595 842
D [17/Jun/2017:15:42:04 +0200] [Job 8] %%Pages: 1
D [17/Jun/2017:15:42:04 +0200] [Job 8] %%EndComments
D [17/Jun/2017:15:42:04 +0200] [Job 8] Before copy_prolog - %%BeginProlog
D [17/Jun/2017:15:42:04 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:04 +0200] [Job 8] Before copy_setup - %%BeginSetup
D [17/Jun/2017:15:42:04 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:05 +0200] [Job 8] Before page loop - %%Page: 1 1
D [17/Jun/2017:15:42:05 +0200] [Job 8] Copying page 1...
D [17/Jun/2017:15:42:05 +0200] [Job 8] pagew = 571.0, pagel = 818.0
D [17/Jun/2017:15:42:05 +0200] [Job 8] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [17/Jun/2017:15:42:05 +0200] [Job 8] PageLeft = 12.0, PageRight = 583.0
D [17/Jun/2017:15:42:05 +0200] [Job 8] PageTop = 830.0, PageBottom = 12.0
D [17/Jun/2017:15:42:05 +0200] [Job 8] PageWidth = 595.0, PageLength = 842.0
D [17/Jun/2017:15:42:05 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:05 +0200] [Job 8] Wrote 1 pages...
D [17/Jun/2017:15:42:05 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:05 +0200] [Job 8] PID 7024 (pstops) exited with no errors.
D [17/Jun/2017:15:42:05 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:05 +0200] [Job 8] PID 7023 (pdftops) exited with no errors.
D [17/Jun/2017:15:42:05 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:05 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:05 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:05 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:05 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:05 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:05 +0200] [Job 8] PID 6998 (/usr/lib/cups/filter/pdftops) exited with no errors.
D [17/Jun/2017:15:42:05 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:05 +0200] Discarding unused job-state-changed event...
E [17/Jun/2017:15:42:05 +0200] [Job 8] Job stopped due to filter errors; please consult the error_log file for details.
D [17/Jun/2017:15:42:05 +0200] cupsdMarkDirty(---J-)
D [17/Jun/2017:15:42:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Jun/2017:15:42:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [17/Jun/2017:15:42:05 +0200] Discarding unused printer-state-changed event...
D [17/Jun/2017:15:42:05 +0200] [Job 8] The following messages were recorded from 15:42:03 to 15:42:03
D [17/Jun/2017:15:42:05 +0200] [Job 8] Printer found with device ID: MFG:Brother;CMD:PJL,HBP;MDL:HL-1110 series;CLS:PRINTER;CID:Brother Laser Type3; Device URI: usb://Brother/HL-1110%20series?serial=K3N503736
D [17/Jun/2017:15:42:05 +0200] [Job 8] End of messages
D [17/Jun/2017:15:42:05 +0200] [Job 8] printer-state=3(idle)
D [17/Jun/2017:15:42:05 +0200] [Job 8] printer-state-message="Sending data to printer."
D [17/Jun/2017:15:42:05 +0200] [Job 8] printer-state-reasons=none
D [17/Jun/2017:15:42:05 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:06 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:12 +0200] [Client 14] GET /printers/Brother_HL-1110_series HTTP/1.1
D [17/Jun/2017:15:42:12 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"
D [17/Jun/2017:15:42:12 +0200] [Client 14] Authorized as pi using Basic
D [17/Jun/2017:15:42:12 +0200] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[10] = "SERVER_ADMIN=root at raspberrypi"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[11] = "SOFTWARE=CUPS/1.7.5"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[13] = "USER=root"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[15] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[17] = "IPP_PORT=631"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[18] = "AUTH_TYPE=Basic"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[19] = "LANG=en_GB.UTF8"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[22] = "SERVER_NAME=localhost"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[23] = "SERVER_PORT=631"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[24] = "REMOTE_ADDR=[v1.::1]"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[25] = "REMOTE_HOST=localhost"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[26] = "SCRIPT_NAME=/printers/Brother_HL-1110_series"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/printers/Brother_HL-1110_series"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[28] = "PATH_INFO=/Brother_HL-1110_series"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[29] = "REMOTE_USER=pi"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[30] = "SERVER_PROTOCOL=HTTP/1.1"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[31] = "HTTP_COOKIE=org.cups.sid=6f7d3387005105a9caf7a11344736d46"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[32] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux armv6l) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/56.0.2924.84 Safari/537.36"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[33] = "HTTP_REFERER=http://localhost:631/jobs/"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[34] = "REQUEST_METHOD=GET"
D [17/Jun/2017:15:42:12 +0200] [CGI] envp[35] = "QUERY_STRING="
D [17/Jun/2017:15:42:12 +0200] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 7029)
I [17/Jun/2017:15:42:12 +0200] [Client 14] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=7029)
D [17/Jun/2017:15:42:12 +0200] [Client 14] file=15
D [17/Jun/2017:15:42:12 +0200] [Client 14] Waiting for CGI data.
D [17/Jun/2017:15:42:12 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] [CGI] org.cups.sid cookie is "6f7d3387005105a9caf7a11344736d46"
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: SECTION="printers"
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: REFRESH_PAGE=""
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: PRINTER_NAME="Brother_HL-1110_series"
D [17/Jun/2017:15:42:13 +0200] [Client 16] Accepted from localhost (Domain)
D [17/Jun/2017:15:42:13 +0200] [Client 16] Waiting for request.
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] [Client 16] POST / HTTP/1.1
D [17/Jun/2017:15:42:13 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [17/Jun/2017:15:42:13 +0200] [Client 16] No authentication data provided.
D [17/Jun/2017:15:42:13 +0200] [Client 16] 2.0 CUPS-Get-Default 1
D [17/Jun/2017:15:42:13 +0200] CUPS-Get-Default
D [17/Jun/2017:15:42:13 +0200] CUPS-Get-Default client-error-not-found: No default printer.
D [17/Jun/2017:15:42:13 +0200] [Client 16] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [17/Jun/2017:15:42:13 +0200] [Client 16] Content-Length: 113
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x80e075e8(IPP_IDLE), pipe_pid=0, file=-1
D [17/Jun/2017:15:42:13 +0200] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [17/Jun/2017:15:42:13 +0200] [Client 16] bytes=0, http_state=0, data_remaining=0
D [17/Jun/2017:15:42:13 +0200] [Client 16] Waiting for request.
D [17/Jun/2017:15:42:13 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] [Client 16] POST / HTTP/1.1
D [17/Jun/2017:15:42:13 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [17/Jun/2017:15:42:13 +0200] [Client 16] No authentication data provided.
D [17/Jun/2017:15:42:13 +0200] [Client 16] 2.0 Get-Printer-Attributes 2
D [17/Jun/2017:15:42:13 +0200] Get-Printer-Attributes ipp://localhost/printers/Brother_HL-1110_series
D [17/Jun/2017:15:42:13 +0200] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Brother_HL-1110_series) from localhost
D [17/Jun/2017:15:42:13 +0200] [Client 16] Content-Length: 687
D [17/Jun/2017:15:42:13 +0200] [CGI] show_printer(http=0x7ffeba60, printer="Brother_HL-1110_series")
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=687, response=0x80e08930(IPP_IDLE), pipe_pid=0, file=-1
D [17/Jun/2017:15:42:13 +0200] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [17/Jun/2017:15:42:13 +0200] [Client 16] bytes=0, http_state=0, data_remaining=0
D [17/Jun/2017:15:42:13 +0200] [Client 16] Waiting for request.
D [17/Jun/2017:15:42:13 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: REMOTE_USER="pi"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.5"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: REMOTE_USER="pi"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.5"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: printer_is_accepting_jobs[0]="1"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: printer_is_shared[0]="0"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: printer_state[0]="3"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: admin_uri[0]="/admin/"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: printer_uri_supported[0]="/printers/Brother_HL-1110_series"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: printer_name[0]="Brother_HL-1110_series"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: printer_location[0]=""
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: printer_info[0]="Brother HL-1110 series"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: job_sheets_default[0]="none, none"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: device_uri[0]="usb://Brother/HL-1110%20series?serial=K3N503736"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: color_supported[0]="0"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: printer_make_and_model[0]="Brother HL-1110 for CUPS "
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: media_default[0]="iso_a4_210x297mm"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: sides_supported[0]="one-sided"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: sides_default[0]="one-sided"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: printer_commands[0]="AutoConfigure, Clean, PrintSelfTestPage"
D [17/Jun/2017:15:42:13 +0200] [Client 14] CGI data ready to be sent.
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: server_is_sharing_printers[0]="0"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: TITLE="Brother_HL-1110_series"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: REMOTE_USER="pi"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.5"
D [17/Jun/2017:15:42:13 +0200] [Client 16] POST / HTTP/1.1
D [17/Jun/2017:15:42:13 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [17/Jun/2017:15:42:13 +0200] [Client 16] No authentication data provided.
D [17/Jun/2017:15:42:13 +0200] [Client 16] 2.0 Get-Jobs 3
D [17/Jun/2017:15:42:13 +0200] Get-Jobs ipp://localhost:631/printers/Brother_HL-1110_series
D [17/Jun/2017:15:42:13 +0200] [Client 16] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/Brother_HL-1110_series) from localhost
D [17/Jun/2017:15:42:13 +0200] [Client 16] Content-Length: 372
D [17/Jun/2017:15:42:13 +0200] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, response=(nil)(), pipe_pid=7029, file=15
D [17/Jun/2017:15:42:13 +0200] [Client 14] Waiting for CGI data.
D [17/Jun/2017:15:42:13 +0200] Script header: Content-Type: text/html;charset=utf-8
D [17/Jun/2017:15:42:13 +0200] Script header: 
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=372, response=0x80e075e8(IPP_IDLE), pipe_pid=0, file=-1
D [17/Jun/2017:15:42:13 +0200] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [17/Jun/2017:15:42:13 +0200] [Client 16] bytes=0, http_state=0, data_remaining=0
D [17/Jun/2017:15:42:13 +0200] [Client 16] Waiting for request.
D [17/Jun/2017:15:42:13 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [17/Jun/2017:15:42:13 +0200] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=7029, file=15
D [17/Jun/2017:15:42:13 +0200] [Client 14] Waiting for CGI data.
D [17/Jun/2017:15:42:13 +0200] [Client 14] CGI data ready to be sent.
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=7029, file=15
D [17/Jun/2017:15:42:13 +0200] [Client 14] Waiting for CGI data.
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] [CGI] Regular expression ".*Clean.*"
D [17/Jun/2017:15:42:13 +0200] [CGI] matches[0].rm_so=0
D [17/Jun/2017:15:42:13 +0200] [CGI] matches[1].rm_so=-1
D [17/Jun/2017:15:42:13 +0200] [CGI] Regular expression ".*PrintSelfTestPage.*"
D [17/Jun/2017:15:42:13 +0200] [CGI] matches[0].rm_so=0
D [17/Jun/2017:15:42:13 +0200] [CGI] matches[1].rm_so=-1
D [17/Jun/2017:15:42:13 +0200] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=7029, file=15
D [17/Jun/2017:15:42:13 +0200] [Client 14] Waiting for CGI data.
D [17/Jun/2017:15:42:13 +0200] [Client 14] CGI data ready to be sent.
D [17/Jun/2017:15:42:13 +0200] PID 7029 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] [Client 16] HTTP_STATE_WAITING Closing on EOF
D [17/Jun/2017:15:42:13 +0200] [Client 16] Closing connection.
D [17/Jun/2017:15:42:13 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [17/Jun/2017:15:42:13 +0200] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=7029, file=15
D [17/Jun/2017:15:42:13 +0200] [Client 14] Waiting for CGI data.
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiClearVariables called.
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: ORDER="asc"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: SECTION="printers"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: TOTAL="1"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: REMOTE_USER="pi"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.7.5"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: job_preserved[0]="1"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: job_printer_name[0]="Brother_HL-1110_series"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: job_printer_uri[0]="/printers/Brother_HL-1110_series"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: time_at_creation[0]="Sat 17 Jun 2017 15:28:50 CEST"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: time_at_processing[0]="Sat 17 Jun 2017 15:42:02 CEST"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: time_at_completed[0]="novalue"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: job_id[0]="8"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: job_state[0]="6"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: job_media_sheets_completed[0]="0"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: job_k_octets[0]="1"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetArray: job_printer_state_message[0]="Filter failed"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: PRINTER_NAME="Brother_HL-1110_series"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED="/printers/Brother_HL-1110_series"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: THISURL="/printers/Brother_HL-1110_series"
D [17/Jun/2017:15:42:13 +0200] [CGI] cgiSetVariable: SEARCH_DEST="Brother_HL-1110_series"
D [17/Jun/2017:15:42:13 +0200] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=7029, file=15
D [17/Jun/2017:15:42:13 +0200] [Client 14] Waiting for CGI data.
D [17/Jun/2017:15:42:13 +0200] [Client 14] CGI data ready to be sent.
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=7029, file=15
D [17/Jun/2017:15:42:13 +0200] [Client 14] Waiting for CGI data.
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=7029, file=15
D [17/Jun/2017:15:42:13 +0200] [Client 14] Waiting for CGI data.
D [17/Jun/2017:15:42:13 +0200] [Client 14] CGI data ready to be sent.
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=7029, file=15
D [17/Jun/2017:15:42:13 +0200] [Client 14] Waiting for CGI data.
D [17/Jun/2017:15:42:13 +0200] [Client 14] Waiting for request.
D [17/Jun/2017:15:42:13 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:13 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:42:14 +0200] cupsd is not idle any more, canceling shutdown.
I [17/Jun/2017:15:42:33 +0200] Saving job.cache...
D [17/Jun/2017:15:42:33 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [17/Jun/2017:15:42:33 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:47:06 +0200] [Job 8] Unloading...
E [17/Jun/2017:15:47:06 +0200] [Job 8] Stopping unresponsive job.
D [17/Jun/2017:15:47:06 +0200] Report: clients=1
D [17/Jun/2017:15:47:06 +0200] Report: jobs=8
D [17/Jun/2017:15:47:06 +0200] Report: jobs-active=1
D [17/Jun/2017:15:47:06 +0200] Report: printers=1
D [17/Jun/2017:15:47:06 +0200] Report: stringpool-string-count=415
D [17/Jun/2017:15:47:06 +0200] Report: stringpool-alloc-bytes=6048
D [17/Jun/2017:15:47:06 +0200] Report: stringpool-total-bytes=7496
D [17/Jun/2017:15:47:06 +0200] cupsd is not idle any more, canceling shutdown.
D [17/Jun/2017:15:47:14 +0200] Closing client 14 after 300 seconds of inactivity...
D [17/Jun/2017:15:47:14 +0200] [Client 14] Closing connection.
D [17/Jun/2017:15:47:14 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [17/Jun/2017:15:47:14 +0200] cupsd is not idle any more, canceling shutdown.