Bonjour! J'ai de nouveau des soucis avec mon imprimante... Ou plutôt, pas moyen d'imprimer depuis mes deux fedora 27 sur l'imprimante branchée sur un raspberrypi 1 tournant sur arch. (armv6, pas de version de fedora pour celle là)
-Le partage du scanner fonctionne et le port 631 est ouvert. Je ne pense pas qu'il y ait de soucis de ce côté.
-L'imprimante fonctionne sur le serveur, donc pas de problème de paramètres...
-On est sur la même version de pilote : gutenprint 5.2.13
-Cups 2.2.6 sur le serveur Cups 2.2.4 sur le client
Les paramétrages se font bien et l'imprimante est bien vue et par l'interface web de cups et par gnome-config-printer
Seulement voila, quand je tente d'imprimer depuis fedora j'obtiens un "printer not responding"
J'ai essayé plein de trucs, un peu tout, mais je m'arrache les cheveux la : le seul truc bizarre, c'est que coté client, a l'installation de l'imprimante, si il voit bien une canon, il ne détermine pas seul le modèle...
je trouve les logs peu clairs si ce n'est que je n'ai pas l'impression que le client fasse de requête sur le serveur...
Des idées? Merci!!
le error log coté serveur (coté client, c'est vide... )
:17:19:53 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [30/Jan/2018:17:19:53 +0000] [Client 10] Server address is "192.168.0.25".
D [30/Jan/2018:17:19:53 +0000] [Client 10] Accepted from 192.168.0.15:53390 (IPv4)
D [30/Jan/2018:17:19:53 +0000] [Client 10] Waiting for request.
D [30/Jan/2018:17:19:53 +0000] [Job 6] Unloading...
D [30/Jan/2018:17:19:53 +0000] cupsdAddCert: Adding certificate for PID 0
D [30/Jan/2018:17:19:53 +0000] Report: clients=1
D [30/Jan/2018:17:19:53 +0000] Report: jobs=3
D [30/Jan/2018:17:19:53 +0000] Report: jobs-active=0
D [30/Jan/2018:17:19:53 +0000] Report: printers=1
D [30/Jan/2018:17:19:53 +0000] Report: stringpool-string-count=148470
D [30/Jan/2018:17:19:53 +0000] Report: stringpool-alloc-bytes=17840
D [30/Jan/2018:17:19:53 +0000] Report: stringpool-total-bytes=2520008
D [30/Jan/2018:17:19:53 +0000] [Client 10] Connection now encrypted.
D [30/Jan/2018:17:19:53 +0000] [Client 10] GET /admin HTTP/1.1
D [30/Jan/2018:17:19:53 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:19:53 +0000] [Client 10] Read: status=200, state=3
D [30/Jan/2018:17:19:54 +0000] [Client 10] Authorized as "slyholborn" using Basic.
D [30/Jan/2018:17:19:54 +0000] cupsdNetIFUpdate: Ignoring "lo".
D [30/Jan/2018:17:19:54 +0000] cupsdNetIFUpdate: Ignoring "eth0".
D [30/Jan/2018:17:19:54 +0000] cupsdNetIFUpdate: "lo" = localhost:631
D [30/Jan/2018:17:19:54 +0000] cupsdNetIFUpdate: "eth0" = 192.168.0.25:631
D [30/Jan/2018:17:19:54 +0000] cupsdNetIFUpdate: "lo" = localhost:631
D [30/Jan/2018:17:19:54 +0000] cupsdNetIFUpdate: "eth0" = [v1.fe80::ba27:ebff:fec1:ba6+eth0]:631
D [30/Jan/2018:17:19:54 +0000] [Client 10] Processing GET /admin
D [30/Jan/2018:17:19:54 +0000] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[7] = "CUPS_STATEDIR=/run/cups"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[10] = "SERVER_ADMIN=root@julouhome"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[11] = "SOFTWARE=CUPS/2.2.6"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[13] = "USER=root"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[15] = "CUPS_SERVER=/run/cups/cups.sock"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[17] = "IPP_PORT=631"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[18] = "AUTH_TYPE=Basic"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[19] = "LANG=en_US.UTF8"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[22] = "SERVER_NAME=192.168.0.25"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[23] = "SERVER_PORT=631"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[24] = "REMOTE_ADDR=192.168.0.15"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[25] = "REMOTE_HOST=192.168.0.15"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[26] = "SCRIPT_NAME=/admin"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/doc/admin"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[28] = "REMOTE_USER=slyholborn"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[30] = "HTTP_COOKIE=org.cups.sid=2f2a5ace009d9cb42ed4d48c3655226b"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Fedora; Linux x86_64; rv:58.0) Gecko/20100101 Firefox/58.0"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[32] = "HTTP_REFERER=https://192.168.0.25:631/"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[33] = "REQUEST_METHOD=GET"
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[34] = "QUERY_STRING="
D [30/Jan/2018:17:19:54 +0000] [CGI] envp[35] = "HTTPS=ON"
D [30/Jan/2018:17:19:54 +0000] cupsdAddCert: Adding certificate for PID 595
D [30/Jan/2018:17:19:54 +0000] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi (PID 595)
I [30/Jan/2018:17:19:54 +0000] [Client 10] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=595, file=16)
D [30/Jan/2018:17:19:54 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:19:54 +0000] [CGI] admin.cgi started...
D [30/Jan/2018:17:19:54 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:19:54 +0000] [Client 11] Server address is "/run/cups/cups.sock".
D [30/Jan/2018:17:19:54 +0000] [Client 11] Accepted from localhost (Domain)
D [30/Jan/2018:17:19:54 +0000] [Client 11] Waiting for request.
D [30/Jan/2018:17:19:54 +0000] [CGI] http=0x2170f78
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: SECTION=\"admin\"
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
D [30/Jan/2018:17:19:54 +0000] [CGI] org.cups.sid cookie is \"2f2a5ace009d9cb42ed4d48c3655226b\"
D [30/Jan/2018:17:19:54 +0000] [CGI] No form data, showing main menu...
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: DEBUG_LOGGING=\"CHECKED\"
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: REMOTE_ADMIN=\"CHECKED\"
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: REMOTE_ANY=\"CHECKED\"
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: SHARE_PRINTERS=\"CHECKED\"
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: HAVE_GSSAPI=\"1\"
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: KERBEROS=\"\"
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: PRESERVE_JOBS=\"CHECKED\"
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: PRESERVE_JOB_HISTORY=\"Yes\"
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: PRESERVE_JOB_FILES=\"1d\"
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: MAX_CLIENTS=\"100\"
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: MAX_JOBS=\"500\"
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: MAX_LOG_SIZE=\"0\"
D [30/Jan/2018:17:19:54 +0000] [CGI] /usr/share/cups/drivers/pscript5.dll: No such file or directory
D [30/Jan/2018:17:19:54 +0000] [Client 11] POST / HTTP/1.1
D [30/Jan/2018:17:19:54 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:19:54 +0000] [Client 11] Read: status=200, state=6
D [30/Jan/2018:17:19:54 +0000] [Client 11] No authentication data provided.
D [30/Jan/2018:17:19:54 +0000] [Client 11] Read: status=100, state=6
D [30/Jan/2018:17:19:54 +0000] [Client 11] Read: status=100, state=6
D [30/Jan/2018:17:19:54 +0000] [Client 11] Read: status=100, state=6
D [30/Jan/2018:17:19:54 +0000] [Client 11] Read: status=100, state=6
D [30/Jan/2018:17:19:54 +0000] [Client 11] Read: status=100, state=6
D [30/Jan/2018:17:19:54 +0000] [Client 11] 2.0 Get-Subscriptions 1
D [30/Jan/2018:17:19:54 +0000] Get-Subscriptions ipp://localhost/
I [30/Jan/2018:17:19:54 +0000] Expiring subscriptions...
D [30/Jan/2018:17:19:54 +0000] Get-Subscriptions client-error-not-found: No subscriptions found.
D [30/Jan/2018:17:19:54 +0000] [Client 11] Returning IPP client-error-not-found for Get-Subscriptions (ipp://localhost/) from localhost
D [30/Jan/2018:17:19:54 +0000] [Client 11] Content-Length: 117
D [30/Jan/2018:17:19:54 +0000] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [30/Jan/2018:17:19:54 +0000] [Client 11] con->http=0xb26e50
D [30/Jan/2018:17:19:54 +0000] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=117, response=0xae1328(IPP_STATE_DATA), pipe_pid=0, file=-1
D [30/Jan/2018:17:19:54 +0000] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [30/Jan/2018:17:19:54 +0000] [Client 11] bytes=0, http_state=0, data_remaining=117
D [30/Jan/2018:17:19:54 +0000] [Client 11] Flushing write buffer.
D [30/Jan/2018:17:19:54 +0000] [Client 11] New state is HTTP_STATE_WAITING
D [30/Jan/2018:17:19:54 +0000] [Client 11] Waiting for request.
D [30/Jan/2018:17:19:54 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.0.25\"
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"slyholborn\"
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.6\"
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: TITLE=\"Administration\"
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.0.25\"
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"slyholborn\"
D [30/Jan/2018:17:19:54 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.6\"
D [30/Jan/2018:17:19:54 +0000] [Client 10] CGI data ready to be sent.
D [30/Jan/2018:17:19:54 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:19:54 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=595, file=16
D [30/Jan/2018:17:19:54 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:19:54 +0000] [Client 10] Script header: Content-Type: text/html;charset=utf-8
D [30/Jan/2018:17:19:54 +0000] [Client 10] Script header:
D [30/Jan/2018:17:19:54 +0000] [Client 10] Sending status 200 for CGI.
D [30/Jan/2018:17:19:54 +0000] [Client 10] cupsdSendHeader: code=200, type="(null)", auth_type=0
D [30/Jan/2018:17:19:54 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:19:54 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=595, file=16
D [30/Jan/2018:17:19:54 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:19:54 +0000] [Client 10] CGI data ready to be sent.
D [30/Jan/2018:17:19:54 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:19:54 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=595, file=16
D [30/Jan/2018:17:19:54 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:19:54 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:19:54 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=595, file=16
D [30/Jan/2018:17:19:54 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:19:54 +0000] [Client 10] CGI data ready to be sent.
D [30/Jan/2018:17:19:54 +0000] [Client 11] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [30/Jan/2018:17:19:54 +0000] [Client 11] Closing connection.
D [30/Jan/2018:17:19:54 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:19:54 +0000] PID 595 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [30/Jan/2018:17:19:54 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:19:54 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=595, file=16
D [30/Jan/2018:17:19:54 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:19:54 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:19:54 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=595, file=16
D [30/Jan/2018:17:19:54 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:19:54 +0000] [Client 10] CGI data ready to be sent.
D [30/Jan/2018:17:19:54 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:19:54 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=595, file=16
D [30/Jan/2018:17:19:54 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:19:54 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:19:54 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=595, file=16
D [30/Jan/2018:17:19:54 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:19:54 +0000] [Client 10] CGI data ready to be sent.
D [30/Jan/2018:17:19:54 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:19:54 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=595, file=16
D [30/Jan/2018:17:19:54 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:19:54 +0000] [Client 10] Sending 0-length chunk.
D [30/Jan/2018:17:19:54 +0000] [Client 10] Flushing write buffer.
D [30/Jan/2018:17:19:54 +0000] [Client 10] New state is HTTP_STATE_WAITING
D [30/Jan/2018:17:19:54 +0000] [Client 10] Waiting for request.
D [30/Jan/2018:17:19:54 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [30/Jan/2018:17:19:59 +0000] [Client 10] GET /printers/ HTTP/1.1
D [30/Jan/2018:17:19:59 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [30/Jan/2018:17:19:59 +0000] [Client 10] Read: status=200, state=3
D [30/Jan/2018:17:19:59 +0000] [Client 10] Authorized as "slyholborn" using Basic.
D [30/Jan/2018:17:19:59 +0000] [Client 10] Processing GET /printers/
D [30/Jan/2018:17:19:59 +0000] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[7] = "CUPS_STATEDIR=/run/cups"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[10] = "SERVER_ADMIN=root@julouhome"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[11] = "SOFTWARE=CUPS/2.2.6"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[13] = "USER=root"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[15] = "CUPS_SERVER=/run/cups/cups.sock"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[17] = "IPP_PORT=631"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[18] = "AUTH_TYPE=Basic"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[19] = "LANG=en_US.UTF8"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[22] = "SERVER_NAME=192.168.0.25"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[23] = "SERVER_PORT=631"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[24] = "REMOTE_ADDR=192.168.0.15"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[25] = "REMOTE_HOST=192.168.0.15"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[26] = "SCRIPT_NAME=/printers/"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/doc/printers/"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[28] = "REMOTE_USER=slyholborn"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[30] = "HTTP_COOKIE=org.cups.sid=2f2a5ace009d9cb42ed4d48c3655226b"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Fedora; Linux x86_64; rv:58.0) Gecko/20100101 Firefox/58.0"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[32] = "HTTP_REFERER=https://192.168.0.25:631/admin"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[33] = "REQUEST_METHOD=GET"
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[34] = "QUERY_STRING="
D [30/Jan/2018:17:19:59 +0000] [CGI] envp[35] = "HTTPS=ON"
D [30/Jan/2018:17:19:59 +0000] cupsdAddCert: Adding certificate for PID 596
D [30/Jan/2018:17:19:59 +0000] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 596)
I [30/Jan/2018:17:19:59 +0000] [Client 10] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=596, file=16)
D [30/Jan/2018:17:19:59 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:19:59 +0000] [CGI] org.cups.sid cookie is \"2f2a5ace009d9cb42ed4d48c3655226b\"
D [30/Jan/2018:17:19:59 +0000] [CGI] cgiSetVariable: SECTION=\"printers\"
D [30/Jan/2018:17:19:59 +0000] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
D [30/Jan/2018:17:19:59 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:19:59 +0000] [Client 12] Server address is "/run/cups/cups.sock".
D [30/Jan/2018:17:19:59 +0000] [Client 12] Accepted from localhost (Domain)
D [30/Jan/2018:17:19:59 +0000] [Client 12] Waiting for request.
D [30/Jan/2018:17:19:59 +0000] [Client 12] POST / HTTP/1.1
D [30/Jan/2018:17:19:59 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:19:59 +0000] [Client 12] Read: status=200, state=6
D [30/Jan/2018:17:19:59 +0000] [Client 12] No authentication data provided.
D [30/Jan/2018:17:19:59 +0000] [Client 12] Read: status=100, state=6
D [30/Jan/2018:17:19:59 +0000] [Client 12] Read: status=100, state=6
D [30/Jan/2018:17:19:59 +0000] [Client 12] Read: status=100, state=6
D [30/Jan/2018:17:19:59 +0000] [Client 12] Read: status=100, state=6
D [30/Jan/2018:17:19:59 +0000] [Client 12] Read: status=100, state=6
D [30/Jan/2018:17:19:59 +0000] [Client 12] Read: status=100, state=6
D [30/Jan/2018:17:19:59 +0000] [Client 12] 2.0 CUPS-Get-Default 1
D [30/Jan/2018:17:19:59 +0000] CUPS-Get-Default
D [30/Jan/2018:17:19:59 +0000] CUPS-Get-Default client-error-not-found: No default printer.
D [30/Jan/2018:17:19:59 +0000] [Client 12] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [30/Jan/2018:17:19:59 +0000] [Client 12] Content-Length: 113
D [30/Jan/2018:17:19:59 +0000] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [30/Jan/2018:17:19:59 +0000] [Client 12] con->http=0xb26e50
D [30/Jan/2018:17:19:59 +0000] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x58a440(IPP_STATE_DATA), pipe_pid=0, file=-1
D [30/Jan/2018:17:19:59 +0000] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [30/Jan/2018:17:19:59 +0000] [Client 12] bytes=0, http_state=0, data_remaining=113
D [30/Jan/2018:17:19:59 +0000] [Client 12] Flushing write buffer.
D [30/Jan/2018:17:19:59 +0000] [Client 12] New state is HTTP_STATE_WAITING
D [30/Jan/2018:17:19:59 +0000] [Client 12] Waiting for request.
D [30/Jan/2018:17:19:59 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:19:59 +0000] [Client 12] POST / HTTP/1.1
D [30/Jan/2018:17:19:59 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:19:59 +0000] [Client 12] Read: status=200, state=6
D [30/Jan/2018:17:19:59 +0000] [Client 12] No authentication data provided.
D [30/Jan/2018:17:19:59 +0000] [Client 12] 2.0 CUPS-Get-Printers 2
D [30/Jan/2018:17:19:59 +0000] CUPS-Get-Printers
D [30/Jan/2018:17:19:59 +0000] [Client 12] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [30/Jan/2018:17:19:59 +0000] [Client 12] Content-Length: 355
D [30/Jan/2018:17:19:59 +0000] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [30/Jan/2018:17:19:59 +0000] [CGI] show_all_printers(http=0x11e9938, user=\"slyholborn\")
D [30/Jan/2018:17:19:59 +0000] [CGI] cgiSetVariable: TITLE=\"Printers\"
D [30/Jan/2018:17:19:59 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.0.25\"
D [30/Jan/2018:17:19:59 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"slyholborn\"
D [30/Jan/2018:17:19:59 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.6\"
D [30/Jan/2018:17:19:59 +0000] [Client 10] CGI data ready to be sent.
D [30/Jan/2018:17:19:59 +0000] [Client 12] con->http=0xb26e50
D [30/Jan/2018:17:19:59 +0000] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=355, response=0xb01f70(IPP_STATE_DATA), pipe_pid=0, file=-1
D [30/Jan/2018:17:19:59 +0000] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [30/Jan/2018:17:19:59 +0000] [Client 12] bytes=0, http_state=0, data_remaining=355
D [30/Jan/2018:17:19:59 +0000] [Client 12] Flushing write buffer.
D [30/Jan/2018:17:19:59 +0000] [Client 12] New state is HTTP_STATE_WAITING
D [30/Jan/2018:17:19:59 +0000] [Client 12] Waiting for request.
D [30/Jan/2018:17:19:59 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:19:59 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:19:59 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=596, file=16
D [30/Jan/2018:17:19:59 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:19:59 +0000] [Client 10] Script header: Content-Type: text/html;charset=utf-8
D [30/Jan/2018:17:19:59 +0000] [Client 10] Script header:
D [30/Jan/2018:17:19:59 +0000] [Client 10] Sending status 200 for CGI.
D [30/Jan/2018:17:19:59 +0000] [Client 10] cupsdSendHeader: code=200, type="(null)", auth_type=0
D [30/Jan/2018:17:19:59 +0000] [CGI] cgiSetVariable: TOTAL=\"1\"
D [30/Jan/2018:17:19:59 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.0.25\"
D [30/Jan/2018:17:19:59 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"slyholborn\"
D [30/Jan/2018:17:19:59 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.6\"
D [30/Jan/2018:17:19:59 +0000] [CGI] cgiSetArray: printer_state[0]=\"3\"
D [30/Jan/2018:17:19:59 +0000] [CGI] cgiSetArray: printer_state_message[0]=\"\"
D [30/Jan/2018:17:19:59 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:19:59 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=596, file=16
D [30/Jan/2018:17:19:59 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:19:59 +0000] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\"
D [30/Jan/2018:17:19:59 +0000] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/Canon_MP630_series\"
D [30/Jan/2018:17:19:59 +0000] [CGI] cgiSetArray: printer_name[0]=\"Canon_MP630_series\"
D [30/Jan/2018:17:19:59 +0000] [CGI] cgiSetArray: printer_location[0]=\"\"
D [30/Jan/2018:17:19:59 +0000] [CGI] cgiSetArray: printer_info[0]=\"Canon MP630 series\"
D [30/Jan/2018:17:19:59 +0000] [CGI] cgiSetArray: printer_make_and_model[0]=\"Canon MP630 series - CUPS+Gutenprint v5.2.13\"
D [30/Jan/2018:17:19:59 +0000] [CGI] cgiSetVariable: THISURL=\"/printers/\"
D [30/Jan/2018:17:19:59 +0000] [Client 10] CGI data ready to be sent.
D [30/Jan/2018:17:19:59 +0000] [Client 12] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [30/Jan/2018:17:19:59 +0000] [Client 12] Closing connection.
D [30/Jan/2018:17:19:59 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:19:59 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:19:59 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=596, file=16
D [30/Jan/2018:17:19:59 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:19:59 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:19:59 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=596, file=16
D [30/Jan/2018:17:19:59 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:19:59 +0000] [Client 10] CGI data ready to be sent.
D [30/Jan/2018:17:19:59 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:19:59 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=596, file=16
D [30/Jan/2018:17:19:59 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:19:59 +0000] [Client 10] Sending 0-length chunk.
D [30/Jan/2018:17:19:59 +0000] [Client 10] Flushing write buffer.
D [30/Jan/2018:17:19:59 +0000] [Client 10] New state is HTTP_STATE_WAITING
D [30/Jan/2018:17:19:59 +0000] [Client 10] Waiting for request.
D [30/Jan/2018:17:19:59 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [30/Jan/2018:17:19:59 +0000] PID 596 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [30/Jan/2018:17:20:09 +0000] [Client 10] GET /printers/Canon_MP630_series HTTP/1.1
D [30/Jan/2018:17:20:09 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [30/Jan/2018:17:20:09 +0000] [Client 10] Read: status=200, state=3
D [30/Jan/2018:17:20:10 +0000] [Client 10] Authorized as "slyholborn" using Basic.
D [30/Jan/2018:17:20:10 +0000] [Client 10] Processing GET /printers/Canon_MP630_series
D [30/Jan/2018:17:20:10 +0000] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[7] = "CUPS_STATEDIR=/run/cups"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[10] = "SERVER_ADMIN=root@julouhome"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[11] = "SOFTWARE=CUPS/2.2.6"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[13] = "USER=root"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[15] = "CUPS_SERVER=/run/cups/cups.sock"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[17] = "IPP_PORT=631"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[18] = "AUTH_TYPE=Basic"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[19] = "LANG=en_US.UTF8"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[22] = "SERVER_NAME=192.168.0.25"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[23] = "SERVER_PORT=631"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[24] = "REMOTE_ADDR=192.168.0.15"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[25] = "REMOTE_HOST=192.168.0.15"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[26] = "SCRIPT_NAME=/printers/Canon_MP630_series"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/doc/printers/Canon_MP630_series"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[28] = "PATH_INFO=/Canon_MP630_series"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[29] = "REMOTE_USER=slyholborn"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[30] = "SERVER_PROTOCOL=HTTP/1.1"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[31] = "HTTP_COOKIE=org.cups.sid=2f2a5ace009d9cb42ed4d48c3655226b"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[32] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Fedora; Linux x86_64; rv:58.0) Gecko/20100101 Firefox/58.0"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[33] = "HTTP_REFERER=https://192.168.0.25:631/printers/"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[34] = "REQUEST_METHOD=GET"
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[35] = "QUERY_STRING="
D [30/Jan/2018:17:20:10 +0000] [CGI] envp[36] = "HTTPS=ON"
D [30/Jan/2018:17:20:10 +0000] cupsdAddCert: Adding certificate for PID 598
D [30/Jan/2018:17:20:10 +0000] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 598)
I [30/Jan/2018:17:20:10 +0000] [Client 10] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=598, file=16)
D [30/Jan/2018:17:20:10 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:20:10 +0000] [CGI] org.cups.sid cookie is \"2f2a5ace009d9cb42ed4d48c3655226b\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetVariable: SECTION=\"printers\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetVariable: PRINTER_NAME=\"Canon_MP630_series\"
D [30/Jan/2018:17:20:10 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:10 +0000] [Client 13] Server address is "/run/cups/cups.sock".
D [30/Jan/2018:17:20:10 +0000] [Client 13] Accepted from localhost (Domain)
D [30/Jan/2018:17:20:10 +0000] [Client 13] Waiting for request.
D [30/Jan/2018:17:20:10 +0000] [Client 13] POST / HTTP/1.1
D [30/Jan/2018:17:20:10 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:10 +0000] [Client 13] Read: status=200, state=6
D [30/Jan/2018:17:20:10 +0000] [Client 13] No authentication data provided.
D [30/Jan/2018:17:20:10 +0000] [Client 13] Read: status=100, state=6
D [30/Jan/2018:17:20:10 +0000] [Client 13] Read: status=100, state=6
D [30/Jan/2018:17:20:10 +0000] [Client 13] Read: status=100, state=6
D [30/Jan/2018:17:20:10 +0000] [Client 13] Read: status=100, state=6
D [30/Jan/2018:17:20:10 +0000] [Client 13] Read: status=100, state=6
D [30/Jan/2018:17:20:10 +0000] [Client 13] Read: status=100, state=6
D [30/Jan/2018:17:20:10 +0000] [Client 13] 2.0 CUPS-Get-Default 1
D [30/Jan/2018:17:20:10 +0000] CUPS-Get-Default
D [30/Jan/2018:17:20:10 +0000] CUPS-Get-Default client-error-not-found: No default printer.
D [30/Jan/2018:17:20:10 +0000] [Client 13] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [30/Jan/2018:17:20:10 +0000] [Client 13] Content-Length: 113
D [30/Jan/2018:17:20:10 +0000] [Client 13] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [30/Jan/2018:17:20:10 +0000] [Client 13] con->http=0xb26e50
D [30/Jan/2018:17:20:10 +0000] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0xb01f70(IPP_STATE_DATA), pipe_pid=0, file=-1
D [30/Jan/2018:17:20:10 +0000] [Client 13] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [30/Jan/2018:17:20:10 +0000] [Client 13] bytes=0, http_state=0, data_remaining=113
D [30/Jan/2018:17:20:10 +0000] [Client 13] Flushing write buffer.
D [30/Jan/2018:17:20:10 +0000] [Client 13] New state is HTTP_STATE_WAITING
D [30/Jan/2018:17:20:10 +0000] [Client 13] Waiting for request.
D [30/Jan/2018:17:20:10 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:10 +0000] [CGI] show_printer(http=0x22b7938, printer=\"Canon_MP630_series\")
D [30/Jan/2018:17:20:10 +0000] [Client 13] POST / HTTP/1.1
D [30/Jan/2018:17:20:10 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:10 +0000] [Client 13] Read: status=200, state=6
D [30/Jan/2018:17:20:10 +0000] [Client 13] No authentication data provided.
D [30/Jan/2018:17:20:10 +0000] [Client 13] 2.0 Get-Printer-Attributes 2
D [30/Jan/2018:17:20:10 +0000] Get-Printer-Attributes ipp://localhost/printers/Canon_MP630_series
D [30/Jan/2018:17:20:10 +0000] [Client 13] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Canon_MP630_series) from localhost
D [30/Jan/2018:17:20:10 +0000] [Client 13] Content-Length: 703
D [30/Jan/2018:17:20:10 +0000] [Client 13] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [30/Jan/2018:17:20:10 +0000] [Client 13] con->http=0xb26e50
D [30/Jan/2018:17:20:10 +0000] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=703, response=0xb16598(IPP_STATE_DATA), pipe_pid=0, file=-1
D [30/Jan/2018:17:20:10 +0000] [Client 13] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [30/Jan/2018:17:20:10 +0000] [Client 13] bytes=0, http_state=0, data_remaining=703
D [30/Jan/2018:17:20:10 +0000] [Client 13] Flushing write buffer.
D [30/Jan/2018:17:20:10 +0000] [Client 13] New state is HTTP_STATE_WAITING
D [30/Jan/2018:17:20:10 +0000] [Client 13] Waiting for request.
D [30/Jan/2018:17:20:10 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.0.25\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"slyholborn\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.6\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.0.25\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"slyholborn\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.6\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetArray: printer_is_shared[0]=\"1\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetArray: printer_state[0]=\"3\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/Canon_MP630_series\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetArray: printer_name[0]=\"Canon_MP630_series\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetArray: printer_location[0]=\"\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetArray: printer_info[0]=\"Canon MP630 series\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetArray: device_uri[0]=\"usb://Canon/MP630%20series?serial=3104B9&interface=1\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetArray: color_supported[0]=\"1\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetArray: printer_make_and_model[0]=\"Canon MP630 series - CUPS+Gutenprint v5.2.13\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetArray: media_default[0]=\"iso_a4_210x297mm\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetArray: sides_supported[0]=\"one-sided, two-sided-long-edge, two-sided-short-edge\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetArray: sides_default[0]=\"one-sided\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetArray: printer_commands[0]=\"none\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"1\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetVariable: TITLE=\"Canon_MP630_series\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.0.25\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"slyholborn\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.6\"
D [30/Jan/2018:17:20:10 +0000] [Client 10] CGI data ready to be sent.
D [30/Jan/2018:17:20:10 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:20:10 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=598, file=16
D [30/Jan/2018:17:20:10 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:20:10 +0000] [Client 10] Script header: Content-Type: text/html;charset=utf-8
D [30/Jan/2018:17:20:10 +0000] [Client 10] Script header:
D [30/Jan/2018:17:20:10 +0000] [Client 10] Sending status 200 for CGI.
D [30/Jan/2018:17:20:10 +0000] [Client 10] cupsdSendHeader: code=200, type="(null)", auth_type=0
D [30/Jan/2018:17:20:10 +0000] [CGI] Regular expression \".*Clean.*\"
D [30/Jan/2018:17:20:10 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:20:10 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=598, file=16
D [30/Jan/2018:17:20:10 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:20:10 +0000] [Client 10] CGI data ready to be sent.
D [30/Jan/2018:17:20:10 +0000] [CGI] Regular expression \".*PrintSelfTestPage.*\"
D [30/Jan/2018:17:20:10 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:20:10 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=598, file=16
D [30/Jan/2018:17:20:10 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:20:10 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:20:10 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=598, file=16
D [30/Jan/2018:17:20:10 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:20:10 +0000] [Client 10] CGI data ready to be sent.
D [30/Jan/2018:17:20:10 +0000] [Client 13] POST / HTTP/1.1
D [30/Jan/2018:17:20:10 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:10 +0000] [Client 13] Read: status=200, state=6
D [30/Jan/2018:17:20:10 +0000] [Client 13] No authentication data provided.
D [30/Jan/2018:17:20:10 +0000] [Client 13] 2.0 Get-Jobs 3
D [30/Jan/2018:17:20:10 +0000] Get-Jobs ipp://localhost:631/printers/Canon_MP630_series
D [30/Jan/2018:17:20:10 +0000] [Client 13] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/Canon_MP630_series) from localhost
D [30/Jan/2018:17:20:10 +0000] [Client 13] Content-Length: 75
D [30/Jan/2018:17:20:10 +0000] [Client 13] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [30/Jan/2018:17:20:10 +0000] [Client 13] con->http=0xb26e50
D [30/Jan/2018:17:20:10 +0000] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x56ed90(IPP_STATE_DATA), pipe_pid=0, file=-1
D [30/Jan/2018:17:20:10 +0000] [Client 13] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [30/Jan/2018:17:20:10 +0000] [Client 13] bytes=0, http_state=0, data_remaining=75
D [30/Jan/2018:17:20:10 +0000] [Client 13] Flushing write buffer.
D [30/Jan/2018:17:20:10 +0000] [Client 13] New state is HTTP_STATE_WAITING
D [30/Jan/2018:17:20:10 +0000] [Client 13] Waiting for request.
D [30/Jan/2018:17:20:10 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:10 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:20:10 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=598, file=16
D [30/Jan/2018:17:20:10 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:20:10 +0000] [Client 13] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [30/Jan/2018:17:20:10 +0000] [Client 13] Closing connection.
D [30/Jan/2018:17:20:10 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:10 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:20:10 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=598, file=16
D [30/Jan/2018:17:20:10 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiClearVariables called.
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetVariable: SECTION=\"printers\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetVariable: TOTAL=\"0\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetVariable: PRINTER_NAME=\"Canon_MP630_series\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/Canon_MP630_series\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetVariable: THISURL=\"/printers/Canon_MP630_series\"
D [30/Jan/2018:17:20:10 +0000] [CGI] cgiSetVariable: SEARCH_DEST=\"Canon_MP630_series\"
D [30/Jan/2018:17:20:10 +0000] [Client 10] CGI data ready to be sent.
D [30/Jan/2018:17:20:10 +0000] [Client 10] con->http=0xafed80
D [30/Jan/2018:17:20:10 +0000] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=598, file=16
D [30/Jan/2018:17:20:10 +0000] [Client 10] Waiting for CGI data.
D [30/Jan/2018:17:20:10 +0000] [Client 10] Sending 0-length chunk.
D [30/Jan/2018:17:20:10 +0000] [Client 10] Flushing write buffer.
D [30/Jan/2018:17:20:10 +0000] [Client 10] New state is HTTP_STATE_WAITING
D [30/Jan/2018:17:20:10 +0000] [Client 10] Waiting for request.
D [30/Jan/2018:17:20:10 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [30/Jan/2018:17:20:10 +0000] PID 598 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [30/Jan/2018:17:20:21 +0000] [Client 10] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [30/Jan/2018:17:20:21 +0000] [Client 10] Closing connection.
D [30/Jan/2018:17:20:21 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [30/Jan/2018:17:20:21 +0000] [Client 10] Waiting for socket close.
D [30/Jan/2018:17:20:21 +0000] [Client 10] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [30/Jan/2018:17:20:21 +0000] [Client 10] Closing connection.
D [30/Jan/2018:17:20:21 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [30/Jan/2018:17:20:23 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [30/Jan/2018:17:20:23 +0000] [Client 14] Server address is "192.168.0.25".
D [30/Jan/2018:17:20:23 +0000] [Client 14] Accepted from 192.168.0.15:53404 (IPv4)
D [30/Jan/2018:17:20:23 +0000] [Client 14] Waiting for request.
D [30/Jan/2018:17:20:23 +0000] [Client 14] Connection now encrypted.
D [30/Jan/2018:17:20:23 +0000] [Client 14] POST /admin/ HTTP/1.1
D [30/Jan/2018:17:20:23 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:23 +0000] [Client 14] Read: status=200, state=6
D [30/Jan/2018:17:20:24 +0000] [Client 14] Authorized as "slyholborn" using Basic.
D [30/Jan/2018:17:20:24 +0000] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[7] = "CUPS_STATEDIR=/run/cups"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[10] = "SERVER_ADMIN=root@julouhome"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[11] = "SOFTWARE=CUPS/2.2.6"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[13] = "USER=root"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[15] = "CUPS_SERVER=/run/cups/cups.sock"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[17] = "IPP_PORT=631"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[18] = "AUTH_TYPE=Basic"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[19] = "LANG=en_US.UTF8"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[22] = "SERVER_NAME=192.168.0.25"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[23] = "SERVER_PORT=631"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[24] = "REMOTE_ADDR=192.168.0.15"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[25] = "REMOTE_HOST=192.168.0.15"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[26] = "SCRIPT_NAME=/admin/"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/doc/admin/"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[28] = "REMOTE_USER=slyholborn"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[30] = "HTTP_COOKIE=org.cups.sid=2f2a5ace009d9cb42ed4d48c3655226b"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Fedora; Linux x86_64; rv:58.0) Gecko/20100101 Firefox/58.0"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[32] = "HTTP_REFERER=https://192.168.0.25:631/printers/Canon_MP630_series"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[33] = "REQUEST_METHOD=POST"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[34] = "CONTENT_LENGTH=98"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[35] = "CONTENT_TYPE=application/x-www-form-urlencoded"
D [30/Jan/2018:17:20:24 +0000] [CGI] envp[36] = "HTTPS=ON"
D [30/Jan/2018:17:20:24 +0000] cupsdAddCert: Adding certificate for PID 601
D [30/Jan/2018:17:20:24 +0000] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi (PID 601)
I [30/Jan/2018:17:20:24 +0000] [Client 14] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=601, file=17)
D [30/Jan/2018:17:20:24 +0000] [Client 14] Waiting for CGI data.
D [30/Jan/2018:17:20:24 +0000] [CGI] admin.cgi started...
D [30/Jan/2018:17:20:24 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:24 +0000] [Client 15] Server address is "/run/cups/cups.sock".
D [30/Jan/2018:17:20:24 +0000] [Client 15] Accepted from localhost (Domain)
D [30/Jan/2018:17:20:24 +0000] [Client 15] Waiting for request.
D [30/Jan/2018:17:20:24 +0000] [CGI] http=0x15aaf78
D [30/Jan/2018:17:20:24 +0000] [CGI] cgiSetVariable: SECTION=\"admin\"
D [30/Jan/2018:17:20:24 +0000] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
D [30/Jan/2018:17:20:24 +0000] [CGI] org.cups.sid cookie is \"2f2a5ace009d9cb42ed4d48c3655226b\"
D [30/Jan/2018:17:20:24 +0000] [CGI] cgiSetVariable: org.cups.sid=\"2f2a5ace009d9cb42ed4d48c3655226b\"
D [30/Jan/2018:17:20:24 +0000] [CGI] cgiSetVariable: printer_name=\"Canon_MP630_series\"
D [30/Jan/2018:17:20:24 +0000] [CGI] cgiSetVariable: OP=\"set-allowed-users\"
D [30/Jan/2018:17:20:24 +0000] [CGI] op=\"set-allowed-users\"...
D [30/Jan/2018:17:20:24 +0000] [Client 15] POST / HTTP/1.1
D [30/Jan/2018:17:20:24 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:24 +0000] [Client 15] Read: status=200, state=6
D [30/Jan/2018:17:20:24 +0000] [Client 15] No authentication data provided.
D [30/Jan/2018:17:20:24 +0000] [Client 15] Read: status=100, state=6
D [30/Jan/2018:17:20:24 +0000] [Client 15] Read: status=100, state=6
D [30/Jan/2018:17:20:24 +0000] [Client 15] Read: status=100, state=6
D [30/Jan/2018:17:20:24 +0000] [Client 15] Read: status=100, state=6
D [30/Jan/2018:17:20:24 +0000] [Client 15] Read: status=100, state=6
D [30/Jan/2018:17:20:24 +0000] [Client 15] Read: status=100, state=6
D [30/Jan/2018:17:20:24 +0000] [Client 15] Read: status=100, state=6
D [30/Jan/2018:17:20:24 +0000] [Client 15] 2.0 Get-Printer-Attributes 1
D [30/Jan/2018:17:20:24 +0000] Get-Printer-Attributes ipp://localhost/printers/Canon_MP630_series
D [30/Jan/2018:17:20:24 +0000] [Client 15] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Canon_MP630_series) from localhost
D [30/Jan/2018:17:20:24 +0000] [Client 15] Content-Length: 75
D [30/Jan/2018:17:20:24 +0000] [Client 15] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [30/Jan/2018:17:20:24 +0000] [Client 15] con->http=0xb26e50
D [30/Jan/2018:17:20:24 +0000] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0xb429a8(IPP_STATE_DATA), pipe_pid=0, file=-1
D [30/Jan/2018:17:20:24 +0000] [Client 15] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [30/Jan/2018:17:20:24 +0000] [Client 15] bytes=0, http_state=0, data_remaining=75
D [30/Jan/2018:17:20:24 +0000] [Client 15] Flushing write buffer.
D [30/Jan/2018:17:20:24 +0000] [Client 15] New state is HTTP_STATE_WAITING
D [30/Jan/2018:17:20:24 +0000] [Client 15] Waiting for request.
D [30/Jan/2018:17:20:24 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:24 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.0.25\"
D [30/Jan/2018:17:20:24 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"slyholborn\"
D [30/Jan/2018:17:20:24 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.6\"
D [30/Jan/2018:17:20:24 +0000] [CGI] cgiSetVariable: TITLE=\"Set Allowed Users\"
D [30/Jan/2018:17:20:24 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.0.25\"
D [30/Jan/2018:17:20:24 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"slyholborn\"
D [30/Jan/2018:17:20:24 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.6\"
D [30/Jan/2018:17:20:24 +0000] [Client 14] CGI data ready to be sent.
D [30/Jan/2018:17:20:24 +0000] [Client 14] con->http=0xafed80
D [30/Jan/2018:17:20:24 +0000] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=601, file=17
D [30/Jan/2018:17:20:24 +0000] [Client 14] Waiting for CGI data.
D [30/Jan/2018:17:20:24 +0000] [Client 14] Script header: Content-Type: text/html;charset=utf-8
D [30/Jan/2018:17:20:24 +0000] [Client 14] Script header:
D [30/Jan/2018:17:20:24 +0000] [Client 14] Sending status 200 for CGI.
D [30/Jan/2018:17:20:24 +0000] [Client 14] cupsdSendHeader: code=200, type="(null)", auth_type=0
D [30/Jan/2018:17:20:24 +0000] PID 601 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [30/Jan/2018:17:20:24 +0000] [Client 14] con->http=0xafed80
D [30/Jan/2018:17:20:24 +0000] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=601, file=17
D [30/Jan/2018:17:20:24 +0000] [Client 14] Waiting for CGI data.
D [30/Jan/2018:17:20:24 +0000] [Client 14] CGI data ready to be sent.
D [30/Jan/2018:17:20:24 +0000] [Client 15] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [30/Jan/2018:17:20:24 +0000] [Client 15] Closing connection.
D [30/Jan/2018:17:20:24 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:24 +0000] [Client 14] con->http=0xafed80
D [30/Jan/2018:17:20:24 +0000] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=601, file=17
D [30/Jan/2018:17:20:24 +0000] [Client 14] Waiting for CGI data.
D [30/Jan/2018:17:20:24 +0000] [Client 14] con->http=0xafed80
D [30/Jan/2018:17:20:24 +0000] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=601, file=17
D [30/Jan/2018:17:20:24 +0000] [Client 14] Waiting for CGI data.
D [30/Jan/2018:17:20:24 +0000] [Client 14] CGI data ready to be sent.
D [30/Jan/2018:17:20:24 +0000] [Client 14] con->http=0xafed80
D [30/Jan/2018:17:20:24 +0000] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=601, file=17
D [30/Jan/2018:17:20:24 +0000] [Client 14] Waiting for CGI data.
D [30/Jan/2018:17:20:24 +0000] [Client 14] Sending 0-length chunk.
D [30/Jan/2018:17:20:24 +0000] [Client 14] Flushing write buffer.
D [30/Jan/2018:17:20:24 +0000] [Client 14] New state is HTTP_STATE_WAITING
D [30/Jan/2018:17:20:24 +0000] [Client 14] Waiting for request.
D [30/Jan/2018:17:20:24 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [30/Jan/2018:17:20:35 +0000] [Client 14] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [30/Jan/2018:17:20:35 +0000] [Client 14] Closing connection.
D [30/Jan/2018:17:20:35 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [30/Jan/2018:17:20:35 +0000] [Client 14] Waiting for socket close.
D [30/Jan/2018:17:20:35 +0000] [Client 14] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [30/Jan/2018:17:20:35 +0000] [Client 14] Closing connection.
D [30/Jan/2018:17:20:35 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [30/Jan/2018:17:20:35 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [30/Jan/2018:17:20:35 +0000] [Client 16] Server address is "192.168.0.25".
D [30/Jan/2018:17:20:35 +0000] [Client 16] Accepted from 192.168.0.15:53412 (IPv4)
D [30/Jan/2018:17:20:35 +0000] [Client 16] Waiting for request.
D [30/Jan/2018:17:20:35 +0000] [Client 16] Connection now encrypted.
D [30/Jan/2018:17:20:35 +0000] [Client 16] POST /admin HTTP/1.1
D [30/Jan/2018:17:20:35 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:35 +0000] [Client 16] Read: status=200, state=6
D [30/Jan/2018:17:20:36 +0000] [Client 16] Authorized as "slyholborn" using Basic.
D [30/Jan/2018:17:20:36 +0000] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[7] = "CUPS_STATEDIR=/run/cups"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[10] = "SERVER_ADMIN=root@julouhome"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[11] = "SOFTWARE=CUPS/2.2.6"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[13] = "USER=root"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[15] = "CUPS_SERVER=/run/cups/cups.sock"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[17] = "IPP_PORT=631"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[18] = "AUTH_TYPE=Basic"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[19] = "LANG=en_US.UTF8"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[22] = "SERVER_NAME=192.168.0.25"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[23] = "SERVER_PORT=631"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[24] = "REMOTE_ADDR=192.168.0.15"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[25] = "REMOTE_HOST=192.168.0.15"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[26] = "SCRIPT_NAME=/admin"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/doc/admin"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[28] = "REMOTE_USER=slyholborn"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[30] = "HTTP_COOKIE=org.cups.sid=2f2a5ace009d9cb42ed4d48c3655226b"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Fedora; Linux x86_64; rv:58.0) Gecko/20100101 Firefox/58.0"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[32] = "HTTP_REFERER=https://192.168.0.25:631/admin/"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[33] = "REQUEST_METHOD=POST"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[34] = "CONTENT_LENGTH=151"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[35] = "CONTENT_TYPE=application/x-www-form-urlencoded"
D [30/Jan/2018:17:20:36 +0000] [CGI] envp[36] = "HTTPS=ON"
D [30/Jan/2018:17:20:36 +0000] cupsdAddCert: Adding certificate for PID 603
D [30/Jan/2018:17:20:36 +0000] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi (PID 603)
I [30/Jan/2018:17:20:36 +0000] [Client 16] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=603, file=17)
D [30/Jan/2018:17:20:36 +0000] [Client 16] Waiting for CGI data.
D [30/Jan/2018:17:20:36 +0000] [CGI] admin.cgi started...
D [30/Jan/2018:17:20:36 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:36 +0000] [Client 17] Server address is "/run/cups/cups.sock".
D [30/Jan/2018:17:20:36 +0000] [Client 17] Accepted from localhost (Domain)
D [30/Jan/2018:17:20:36 +0000] [Client 17] Waiting for request.
D [30/Jan/2018:17:20:36 +0000] [CGI] http=0x657f78
D [30/Jan/2018:17:20:36 +0000] [CGI] cgiSetVariable: SECTION=\"admin\"
D [30/Jan/2018:17:20:36 +0000] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
D [30/Jan/2018:17:20:36 +0000] [CGI] org.cups.sid cookie is \"2f2a5ace009d9cb42ed4d48c3655226b\"
D [30/Jan/2018:17:20:36 +0000] [CGI] cgiSetVariable: org.cups.sid=\"2f2a5ace009d9cb42ed4d48c3655226b\"
D [30/Jan/2018:17:20:36 +0000] [CGI] cgiSetVariable: OP=\"set-allowed-users\"
D [30/Jan/2018:17:20:36 +0000] [CGI] cgiSetVariable: PRINTER_NAME=\"Canon_MP630_series\"
D [30/Jan/2018:17:20:36 +0000] [CGI] cgiSetVariable: users=\"192.168.0.15\"
D [30/Jan/2018:17:20:36 +0000] [CGI] cgiSetVariable: type=\"requesting-user-name-allowed\"
D [30/Jan/2018:17:20:36 +0000] [CGI] op=\"set-allowed-users\"...
D [30/Jan/2018:17:20:36 +0000] [Client 17] POST /admin/ HTTP/1.1
D [30/Jan/2018:17:20:36 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:36 +0000] [Client 17] Read: status=200, state=6
D [30/Jan/2018:17:20:36 +0000] [Client 17] No authentication data provided.
D [30/Jan/2018:17:20:36 +0000] [Client 17] Read: status=100, state=6
D [30/Jan/2018:17:20:36 +0000] [Client 17] Read: status=100, state=6
D [30/Jan/2018:17:20:36 +0000] [Client 17] Read: status=100, state=6
D [30/Jan/2018:17:20:36 +0000] [Client 17] Read: status=100, state=6
D [30/Jan/2018:17:20:36 +0000] [Client 17] Read: status=100, state=6
D [30/Jan/2018:17:20:36 +0000] [Client 17] Read: status=100, state=6
D [30/Jan/2018:17:20:36 +0000] [Client 17] 2.0 CUPS-Add-Modify-Printer 1
D [30/Jan/2018:17:20:36 +0000] CUPS-Add-Modify-Printer ipp://localhost/printers/Canon_MP630_series
D [30/Jan/2018:17:20:36 +0000] cupsdIsAuthorized: username=""
D [30/Jan/2018:17:20:36 +0000] [Client 17] Returning HTTP Unauthorized for CUPS-Add-Modify-Printer (ipp://localhost/printers/Canon_MP630_series) from localhost
D [30/Jan/2018:17:20:36 +0000] [Client 17] cupsdSendHeader: code=401, type="text/html", auth_type=1
D [30/Jan/2018:17:20:36 +0000] [Client 17] WWW-Authenticate: Basic realm=\"CUPS\", trc=\"y\"
D [30/Jan/2018:17:20:36 +0000] [Client 17] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [30/Jan/2018:17:20:36 +0000] [Client 17] Closing connection.
D [30/Jan/2018:17:20:36 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:36 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:36 +0000] [Client 18] Server address is "/run/cups/cups.sock".
D [30/Jan/2018:17:20:36 +0000] [Client 18] Accepted from localhost (Domain)
D [30/Jan/2018:17:20:36 +0000] [Client 18] Waiting for request.
D [30/Jan/2018:17:20:36 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:36 +0000] [Client 19] Server address is "/run/cups/cups.sock".
D [30/Jan/2018:17:20:36 +0000] [Client 19] Accepted from localhost (Domain)
D [30/Jan/2018:17:20:36 +0000] [Client 19] Waiting for request.
D [30/Jan/2018:17:20:36 +0000] [Client 18] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [30/Jan/2018:17:20:36 +0000] [Client 18] Closing connection.
D [30/Jan/2018:17:20:36 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:36 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:36 +0000] [Client 20] Server address is "/run/cups/cups.sock".
D [30/Jan/2018:17:20:36 +0000] [Client 20] Accepted from localhost (Domain)
D [30/Jan/2018:17:20:36 +0000] [Client 20] Waiting for request.
D [30/Jan/2018:17:20:36 +0000] [Client 19] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [30/Jan/2018:17:20:36 +0000] [Client 19] Closing connection.
D [30/Jan/2018:17:20:36 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:36 +0000] [Client 20] POST /admin/ HTTP/1.1
D [30/Jan/2018:17:20:36 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:20:36 +0000] [Client 20] Read: status=200, state=6
D [30/Jan/2018:17:20:36 +0000] [Client 20] Authorized as slyholborn using Local.
D [30/Jan/2018:17:20:36 +0000] [Client 20] 2.0 CUPS-Add-Modify-Printer 1
D [30/Jan/2018:17:20:36 +0000] CUPS-Add-Modify-Printer ipp://localhost/printers/Canon_MP630_series
D [30/Jan/2018:17:20:36 +0000] cupsdIsAuthorized: username="slyholborn"
D [30/Jan/2018:17:20:36 +0000] load_ppd: Loading /var/cache/cups/Canon_MP630_series.data...
D [30/Jan/2018:17:20:37 +0000] cupsdRegisterPrinter(p=0x57d178(Canon_MP630_series))
D [30/Jan/2018:17:20:37 +0000] Registering "Canon MP630 series @ julouhome" with DNS-SD type "_printer._tcp".
D [30/Jan/2018:17:20:37 +0000] Registering "Canon MP630 series @ julouhome" with DNS-SD type "_ipps._tcp".
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" printer-type=0xB01E
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" printer-state=3
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" Duplex=T
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" Color=T
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" TLS=1.2
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" UUID=ea29dd46-fef6-3cee-7fdf-1c47680d4f85
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" product=(Canon MP630 series)
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" priority=0
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" adminurl=https://julouhome.local.:631/printers/Canon_MP630_series
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" ty=Canon MP630 series - CUPS+Gutenprint v5.2.13
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" rp=printers/Canon_MP630_series
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" qtotal=1
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" txtvers=1
D [30/Jan/2018:17:20:37 +0000] Registering "Canon MP630 series @ julouhome" with DNS-SD type "_ipp._tcp".
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" printer-type=0xB01E
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" printer-state=3
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" Duplex=T
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" Color=T
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" TLS=1.2
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" UUID=ea29dd46-fef6-3cee-7fdf-1c47680d4f85
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" product=(Canon MP630 series)
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" priority=0
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" adminurl=https://julouhome.local.:631/printers/Canon_MP630_series
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" ty=Canon MP630 series - CUPS+Gutenprint v5.2.13
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" rp=printers/Canon_MP630_series
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" qtotal=1
D [30/Jan/2018:17:20:37 +0000] DNS_SD "Canon MP630 series @ julouhome" txtvers=1
D [30/Jan/2018:17:20:37 +0000] cupsdMarkDirty(P----)
D [30/Jan/2018:17:20:37 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [30/Jan/2018:17:20:37 +0000] cupsdMarkDirty(--p--)
D [30/Jan/2018:17:20:37 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [30/Jan/2018:17:20:37 +0000] Discarding unused printer-modified event...
I [30/Jan/2018:17:20:37 +0000] Printer "Canon_MP630_series" modified by "slyholborn".
D [30/Jan/2018:17:20:37 +0000] [Client 20] Returning IPP successful-ok for CUPS-Add-Modify-Printer (ipp://localhost/printers/Canon_MP630_series) from localhost
D [30/Jan/2018:17:20:37 +0000] [Client 20] Content-Length: 75
D [30/Jan/2018:17:20:37 +0000] [Client 20] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [30/Jan/2018:17:20:37 +0000] [Client 20] con->http=0xb26e50
D [30/Jan/2018:17:20:37 +0000] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0xae1328(IPP_STATE_DATA), pipe_pid=0, file=-1
D [30/Jan/2018:17:20:37 +0000] [Client 20] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [30/Jan/2018:17:20:37 +0000] [Client 20] bytes=0, http_state=0, data_remaining=75
D [30/Jan/2018:17:20:37 +0000] [Client 20] Flushing write buffer.
D [30/Jan/2018:17:20:37 +0000] [Client 20] New state is HTTP_STATE_WAITING
D [30/Jan/2018:17:20:37 +0000] [Client 20] Waiting for request.
D [30/Jan/2018:17:20:37 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [30/Jan/2018:17:20:37 +0000] [CGI] cgiSetVariable: refresh_page=\"5;URL=/admin/?OP=redirect&URL=/printers/Canon_MP630_series\"
D [30/Jan/2018:17:20:37 +0000] [CGI] cgiSetVariable: TITLE=\"Set Allowed Users\"
D [30/Jan/2018:17:20:37 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.0.25\"
D [30/Jan/2018:17:20:37 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"slyholborn\"
D [30/Jan/2018:17:20:37 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.6\"
D [30/Jan/2018:17:20:37 +0000] [Client 16] CGI data ready to be sent.
D [30/Jan/2018:17:20:37 +0000] [Client 16] con->http=0xafed80
D [30/Jan/2018:17:20:37 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=603, file=17
D [30/Jan/2018:17:20:37 +0000] [Client 16] Waiting for CGI data.
D [30/Jan/2018:17:20:37 +0000] [Client 16] Script header: Content-Type: text/html;charset=utf-8
D [30/Jan/2018:17:20:37 +0000] [Client 16] Script header:
D [30/Jan/2018:17:20:37 +0000] [Client 16] Sending status 200 for CGI.
D [30/Jan/2018:17:20:37 +0000] [Client 16] cupsdSendHeader: code=200, type="(null)", auth_type=0
D [30/Jan/2018:17:20:37 +0000] [Client 16] con->http=0xafed80
D [30/Jan/2018:17:20:37 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=603, file=17
D [30/Jan/2018:17:20:37 +0000] [Client 16] Waiting for CGI data.
D [30/Jan/2018:17:20:37 +0000] [Client 16] CGI data ready to be sent.
D [30/Jan/2018:17:20:37 +0000] [Client 16] con->http=0xafed80
D [30/Jan/2018:17:20:37 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=603, file=17
D [30/Jan/2018:17:20:37 +0000] [Client 16] Waiting for CGI data.
D [30/Jan/2018:17:20:37 +0000] [Client 16] con->http=0xafed80
D [30/Jan/2018:17:20:37 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=603, file=17
D [30/Jan/2018:17:20:37 +0000] [Client 16] Waiting for CGI data.
D [30/Jan/2018:17:20:38 +0000] [Client 16] CGI data ready to be sent.
D [30/Jan/2018:17:20:38 +0000] [Client 20] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [30/Jan/2018:17:20:38 +0000] [Client 20] Closing connection.
D [30/Jan/2018:17:20:38 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [30/Jan/2018:17:20:38 +0000] PID 603 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [30/Jan/2018:17:20:38 +0000] [Client 16] con->http=0xafed80
D [30/Jan/2018:17:20:38 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=603, file=17
D [30/Jan/2018:17:20:38 +0000] [Client 16] Waiting for CGI data.
D [30/Jan/2018:17:20:38 +0000] [Client 16] con->http=0xafed80
D [30/Jan/2018:17:20:38 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=603, file=17
D [30/Jan/2018:17:20:38 +0000] [Client 16] Waiting for CGI data.
D [30/Jan/2018:17:20:38 +0000] [Client 16] CGI data ready to be sent.
D [30/Jan/2018:17:20:38 +0000] [Client 16] con->http=0xafed80
D [30/Jan/2018:17:20:38 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=603, file=17
D [30/Jan/2018:17:20:38 +0000] [Client 16] Waiting for CGI data.
D [30/Jan/2018:17:20:38 +0000] [Client 16] Sending 0-length chunk.
D [30/Jan/2018:17:20:38 +0000] [Client 16] Flushing write buffer.
D [30/Jan/2018:17:20:38 +0000] [Client 16] New state is HTTP_STATE_WAITING
D [30/Jan/2018:17:20:38 +0000] [Client 16] Waiting for request.
D [30/Jan/2018:17:20:38 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [30/Jan/2018:17:20:42 +0000] [Client 16] GET /admin HTTP/1.1
D [30/Jan/2018:17:20:42 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [30/Jan/2018:17:20:42 +0000] [Client 16] Read: status=200, state=3
D [30/Jan/2018:17:20:43 +0000] [Client 16] Authorized as "slyholborn" using Basic.
D [30/Jan/2018:17:20:43 +0000] [Client 16] Processing GET /admin
D [30/Jan/2018:17:20:43 +0000] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[7] = "CUPS_STATEDIR=/run/cups"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[10] = "SERVER_ADMIN=root@julouhome"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[11] = "SOFTWARE=CUPS/2.2.6"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[13] = "USER=root"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[15] = "CUPS_SERVER=/run/cups/cups.sock"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[17] = "IPP_PORT=631"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[18] = "AUTH_TYPE=Basic"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[19] = "LANG=en_US.UTF8"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[22] = "SERVER_NAME=192.168.0.25"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[23] = "SERVER_PORT=631"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[24] = "REMOTE_ADDR=192.168.0.15"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[25] = "REMOTE_HOST=192.168.0.15"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[26] = "SCRIPT_NAME=/admin"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/doc/admin"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[28] = "REMOTE_USER=slyholborn"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[30] = "HTTP_COOKIE=org.cups.sid=2f2a5ace009d9cb42ed4d48c3655226b"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Fedora; Linux x86_64; rv:58.0) Gecko/20100101 Firefox/58.0"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[32] = "HTTP_REFERER=https://192.168.0.25:631/"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[33] = "REQUEST_METHOD=GET"
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[34] = "QUERY_STRING="
D [30/Jan/2018:17:20:43 +0000] [CGI] envp[35] = "HTTPS=ON"
D [30/Jan/2018:17:20:43 +0000] cupsdAddCert: Adding certificate for PID 604
D [30/Jan/2018:17:20:43 +0000] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi (PID 604)
I [30/Jan/2018:17:20:43 +0000] [Client 16] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=604, file=16)
D [30/Jan/2018:17:20:43 +0000] [Client 16] Waiting for CGI data.
D [30/Jan/2018:17:20:43 +0000] [CGI] admin.cgi started...
D [30/Jan/2018:17:20:43 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [30/Jan/2018:17:20:43 +0000] [Client 21] Server address is "/run/cups/cups.sock".
D [30/Jan/2018:17:20:43 +0000] [Client 21] Accepted from localhost (Domain)
D [30/Jan/2018:17:20:43 +0000] [Client 21] Waiting for request.
D [30/Jan/2018:17:20:43 +0000] [CGI] http=0x1d1cf78
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: SECTION=\"admin\"
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
D [30/Jan/2018:17:20:43 +0000] [CGI] org.cups.sid cookie is \"2f2a5ace009d9cb42ed4d48c3655226b\"
D [30/Jan/2018:17:20:43 +0000] [CGI] No form data, showing main menu...
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: DEBUG_LOGGING=\"CHECKED\"
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: REMOTE_ADMIN=\"CHECKED\"
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: REMOTE_ANY=\"CHECKED\"
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: SHARE_PRINTERS=\"CHECKED\"
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: HAVE_GSSAPI=\"1\"
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: KERBEROS=\"\"
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: PRESERVE_JOBS=\"CHECKED\"
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: PRESERVE_JOB_HISTORY=\"Yes\"
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: PRESERVE_JOB_FILES=\"1d\"
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: MAX_CLIENTS=\"100\"
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: MAX_JOBS=\"500\"
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: MAX_LOG_SIZE=\"0\"
D [30/Jan/2018:17:20:43 +0000] [CGI] /usr/share/cups/drivers/pscript5.dll: No such file or directory
D [30/Jan/2018:17:20:43 +0000] [Client 21] POST / HTTP/1.1
D [30/Jan/2018:17:20:43 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [30/Jan/2018:17:20:43 +0000] [Client 21] Read: status=200, state=6
D [30/Jan/2018:17:20:43 +0000] [Client 21] No authentication data provided.
D [30/Jan/2018:17:20:43 +0000] [Client 21] Read: status=100, state=6
D [30/Jan/2018:17:20:43 +0000] [Client 21] Read: status=100, state=6
D [30/Jan/2018:17:20:43 +0000] [Client 21] Read: status=100, state=6
D [30/Jan/2018:17:20:43 +0000] [Client 21] Read: status=100, state=6
D [30/Jan/2018:17:20:43 +0000] [Client 21] Read: status=100, state=6
D [30/Jan/2018:17:20:43 +0000] [Client 21] 2.0 Get-Subscriptions 1
D [30/Jan/2018:17:20:43 +0000] Get-Subscriptions ipp://localhost/
I [30/Jan/2018:17:20:43 +0000] Expiring subscriptions...
D [30/Jan/2018:17:20:43 +0000] Get-Subscriptions client-error-not-found: No subscriptions found.
D [30/Jan/2018:17:20:43 +0000] [Client 21] Returning IPP client-error-not-found for Get-Subscriptions (ipp://localhost/) from localhost
D [30/Jan/2018:17:20:43 +0000] [Client 21] Content-Length: 117
D [30/Jan/2018:17:20:43 +0000] [Client 21] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [30/Jan/2018:17:20:43 +0000] [Client 21] con->http=0xb26e50
D [30/Jan/2018:17:20:43 +0000] [Client 21] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=117, response=0xae1328(IPP_STATE_DATA), pipe_pid=0, file=-1
D [30/Jan/2018:17:20:43 +0000] [Client 21] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [30/Jan/2018:17:20:43 +0000] [Client 21] bytes=0, http_state=0, data_remaining=117
D [30/Jan/2018:17:20:43 +0000] [Client 21] Flushing write buffer.
D [30/Jan/2018:17:20:43 +0000] [Client 21] New state is HTTP_STATE_WAITING
D [30/Jan/2018:17:20:43 +0000] [Client 21] Waiting for request.
D [30/Jan/2018:17:20:43 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.0.25\"
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"slyholborn\"
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.6\"
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: TITLE=\"Administration\"
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.0.25\"
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"slyholborn\"
D [30/Jan/2018:17:20:43 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.6\"
D [30/Jan/2018:17:20:43 +0000] [Client 16] CGI data ready to be sent.
D [30/Jan/2018:17:20:43 +0000] [Client 16] con->http=0xafed80
D [30/Jan/2018:17:20:43 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=604, file=16
D [30/Jan/2018:17:20:43 +0000] [Client 16] Waiting for CGI data.
D [30/Jan/2018:17:20:43 +0000] [Client 16] Script header: Content-Type: text/html;charset=utf-8
D [30/Jan/2018:17:20:43 +0000] [Client 16] Script header:
D [30/Jan/2018:17:20:43 +0000] [Client 16] Sending status 200 for CGI.
D [30/Jan/2018:17:20:43 +0000] [Client 16] cupsdSendHeader: code=200, type="(null)", auth_type=0
D [30/Jan/2018:17:20:43 +0000] [Client 16] con->http=0xafed80
D [30/Jan/2018:17:20:43 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=604, file=16
D [30/Jan/2018:17:20:43 +0000] [Client 16] Waiting for CGI data.
D [30/Jan/2018:17:20:43 +0000] [Client 16] CGI data ready to be sent.
D [30/Jan/2018:17:20:43 +0000] [Client 16] con->http=0xafed80
D [30/Jan/2018:17:20:43 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=604, file=16
D [30/Jan/2018:17:20:43 +0000] [Client 16] Waiting for CGI data.
D [30/Jan/2018:17:20:43 +0000] [Client 16] con->http=0xafed80
D [30/Jan/2018:17:20:43 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=604, file=16
D [30/Jan/2018:17:20:43 +0000] [Client 16] Waiting for CGI data.
D [30/Jan/2018:17:20:43 +0000] [Client 16] CGI data ready to be sent.
D [30/Jan/2018:17:20:43 +0000] [Client 16] con->http=0xafed80
D [30/Jan/2018:17:20:43 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=604, file=16
D [30/Jan/2018:17:20:43 +0000] [Client 16] Waiting for CGI data.
D [30/Jan/2018:17:20:43 +0000] PID 604 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [30/Jan/2018:17:20:43 +0000] [Client 16] con->http=0xafed80
D [30/Jan/2018:17:20:43 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=604, file=16
D [30/Jan/2018:17:20:43 +0000] [Client 16] Waiting for CGI data.
D [30/Jan/2018:17:20:43 +0000] [Client 16] CGI data ready to be sent.
D [30/Jan/2018:17:20:43 +0000] [Client 21] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [30/Jan/2018:17:20:43 +0000] [Client 21] Closing connection.
D [30/Jan/2018:17:20:43 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [30/Jan/2018:17:20:43 +0000] [Client 16] con->http=0xafed80
D [30/Jan/2018:17:20:43 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=604, file=16
D [30/Jan/2018:17:20:43 +0000] [Client 16] Waiting for CGI data.
D [30/Jan/2018:17:20:43 +0000] [Client 16] con->http=0xafed80
D [30/Jan/2018:17:20:43 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=604, file=16
D [30/Jan/2018:17:20:43 +0000] [Client 16] Waiting for CGI data.
D [30/Jan/2018:17:20:43 +0000] [Client 16] CGI data ready to be sent.
D [30/Jan/2018:17:20:43 +0000] [Client 16] con->http=0xafed80
D [30/Jan/2018:17:20:43 +0000] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=604, file=16
D [30/Jan/2018:17:20:43 +0000] [Client 16] Waiting for CGI data.
D [30/Jan/2018:17:20:43 +0000] [Client 16] Sending 0-length chunk.
D [30/Jan/2018:17:20:43 +0000] [Client 16] Flushing write buffer.
D [30/Jan/2018:17:20:43 +0000] [Client 16] New state is HTTP_STATE_WAITING
D [30/Jan/2018:17:20:43 +0000] [Client 16] Waiting for request.
D [30/Jan/2018:17:20:43 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [30/Jan/2018:17:20:53 +0000] [Client 16] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [30/Jan/2018:17:20:53 +0000] [Client 16] Closing connection.
D [30/Jan/2018:17:20:53 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [30/Jan/2018:17:20:53 +0000] [Client 16] Waiting for socket close.
D [30/Jan/2018:17:20:53 +0000] Report: clients=1
D [30/Jan/2018:17:20:53 +0000] Report: jobs=3
D [30/Jan/2018:17:20:53 +0000] Report: jobs-active=0
D [30/Jan/2018:17:20:53 +0000] Report: printers=1
D [30/Jan/2018:17:20:53 +0000] Report: stringpool-string-count=148478
D [30/Jan/2018:17:20:53 +0000] Report: stringpool-alloc-bytes=17904
D [30/Jan/2018:17:20:53 +0000] Report: stringpool-total-bytes=2520128
D [30/Jan/2018:17:20:53 +0000] [Client 16] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [30/Jan/2018:17:20:53 +0000] [Client 16] Closing connection.
D [30/Jan/2018:17:20:53 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [30/Jan/2018:17:21:08 +0000] Saving printers.conf...
I [30/Jan/2018:17:21:08 +0000] Generating printcap /etc/printcap...
D [30/Jan/2018:17:21:08 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [30/Jan/2018:17:23:37 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [30/Jan/2018:17:23:37 +0000] [Client 22] Server address is "192.168.0.25".
D [30/Jan/2018:17:23:37 +0000] [Client 22] Accepted from 192.168.0.15:53516 (IPv4)
D [30/Jan/2018:17:23:37 +0000] [Client 22] Waiting for request.
D [30/Jan/2018:17:23:37 +0000] Report: clients=1
D [30/Jan/2018:17:23:37 +0000] Report: jobs=3
D [30/Jan/2018:17:23:37 +0000] Report: jobs-active=0
D [30/Jan/2018:17:23:37 +0000] Report: printers=1
D [30/Jan/2018:17:23:37 +0000] Report: stringpool-string-count=148478
D [30/Jan/2018:17:23:37 +0000] Report: stringpool-alloc-bytes=17904
D [30/Jan/2018:17:23:37 +0000] Report: stringpool-total-bytes=2520128
D [30/Jan/2018:17:23:37 +0000] [Client 22] Connection now encrypted.
D [30/Jan/2018:17:23:37 +0000] [Client 22] GET /admin/log/error_log HTTP/1.1
D [30/Jan/2018:17:23:37 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [30/Jan/2018:17:23:37 +0000] [Client 22] Read: status=200, state=3
D [30/Jan/2018:17:23:38 +0000] [Client 22] Authorized as "slyholborn" using Basic.
D [30/Jan/2018:17:23:38 +0000] cupsdNetIFUpdate: Ignoring "lo".
D [30/Jan/2018:17:23:38 +0000] cupsdNetIFUpdate: Ignoring "eth0".
D [30/Jan/2018:17:23:38 +0000] cupsdNetIFUpdate: "lo" = localhost:631
D [30/Jan/2018:17:23:38 +0000] cupsdNetIFUpdate: "eth0" = 192.168.0.25:631
D [30/Jan/2018:17:23:38 +0000] cupsdNetIFUpdate: "lo" = localhost:631
D [30/Jan/2018:17:23:38 +0000] cupsdNetIFUpdate: "eth0" = [v1.fe80::ba27:ebff:fec1:ba6+eth0]:631
D [30/Jan/2018:17:23:38 +0000] cupsdIsAuthorized: username="slyholborn"
D [30/Jan/2018:17:23:38 +0000] [Client 22] Processing GET /admin/log/error_log