Menu
▾
▴
[Gimp-print-devel] canon selphy 1200: ***Gutenprint 5.3.4 assertion list
|
From: Roger <rog...@gm...> - 2023-03-01 05:38:51
|
On Tue, Feb 28, 2023 at 07:52:36PM -0500, Roger wrote: Using a canon selphy 1200, keep getting a "Filter failed" cups frontend error code. Within the cups error log: Gutenprint: ERROR: ***Gutenprint 5.3.4 assertion list != NULL failed! file print-list.c, line 212. Please report this bug! Roger Full /var/log/cups/error logging: D [28/Feb/2023:19:46:04 -0500] [Client 1] CGI data ready to be sent. D [28/Feb/2023:19:46:04 -0500] PID 15296 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [28/Feb/2023:19:46:04 -0500] [Client 1] con->http=0x55ad51ac0fb0 D [28/Feb/2023:19:46:04 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15296, file=17 D [28/Feb/2023:19:46:04 -0500] [Client 1] Waiting for CGI data. D [28/Feb/2023:19:46:04 -0500] [Client 1] Sending 0-length chunk. D [28/Feb/2023:19:46:04 -0500] [Client 1] Flushing write buffer. D [28/Feb/2023:19:46:04 -0500] [Client 1] New state is HTTP_STATE_WAITING D [28/Feb/2023:19:46:04 -0500] [Client 1] Waiting for request. D [28/Feb/2023:19:46:04 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [28/Feb/2023:19:46:13 -0500] [Client 1] POST /printers/canon_selphy_cp1200 HTTP/1.1 D [28/Feb/2023:19:46:13 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [28/Feb/2023:19:46:13 -0500] [Client 1] Read: status=200, state=6 D [28/Feb/2023:19:46:13 -0500] [Client 1] Authorized as "root" using Basic. D [28/Feb/2023:19:46:13 -0500] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/doc/cups" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[3] = "CUPS_REQUESTROOT=/var/spool/cups" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[4] = "CUPS_SERVERBIN=/usr/lib/cups" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[5] = "CUPS_SERVERROOT=/etc/cups" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[6] = "CUPS_STATEDIR=/run/cups" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[7] = "HOME=/var/spool/cups/tmp" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[8] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[9] = "SERVER_ADMIN=root@4void" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[10] = "SOFTWARE=CUPS/2.4.2" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[11] = "TMPDIR=/var/spool/cups/tmp" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[12] = "USER=root" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[13] = "CUPS_MAX_MESSAGE=2047" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[14] = "CUPS_SERVER=/run/cups/cups.sock" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[16] = "IPP_PORT=631" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[17] = "AUTH_TYPE=Basic" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[18] = "LANG=en_US.UTF8" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[19] = "REDIRECT_STATUS=1" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[21] = "SERVER_NAME=localhost" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[22] = "SERVER_PORT=631" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[23] = "REMOTE_ADDR=[v1.::1]" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[24] = "REMOTE_HOST=localhost" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[25] = "SCRIPT_NAME=/printers/canon_selphy_cp1200" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/doc/cups/printers/canon_selphy_cp1200" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[27] = "PATH_INFO=/canon_selphy_cp1200" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[28] = "REMOTE_USER=root" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[30] = "HTTP_COOKIE=org.cups.sid=dc588e31213b6af2038380c9852a5026" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/110.0.0.0 Safari/537.36" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[32] = "HTTP_REFERER=http://localhost:631/printers/canon_selphy_cp1200" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[33] = "REQUEST_METHOD=POST" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[34] = "CONTENT_LENGTH=64" >D [28/Feb/2023:19:46:13 -0500] [CGI] envp[35] = "CONTENT_TYPE=application/x-www-form-urlencoded" >D [28/Feb/2023:19:46:13 -0500] cupsdAddCert: Adding certificate for PID 15317 >D [28/Feb/2023:19:46:13 -0500] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 15317) >I [28/Feb/2023:19:46:13 -0500] [Client 1] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=15317, file=18) >D [28/Feb/2023:19:46:13 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:13 -0500] [CGI] org.cups.sid cookie is \"dc588e31213b6af2038380c9852a5026\" >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetVariable: org.cups.sid=\"dc588e31213b6af2038380c9852a5026\" >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetVariable: OP=\"print-test-page\" >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetVariable: SECTION=\"printers\" >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetVariable: PRINTER_NAME=\"canon_selphy_cp1200\" >D [28/Feb/2023:19:46:13 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" >D [28/Feb/2023:19:46:13 -0500] [Client 5] Server address is "/run/cups/cups.sock". >D [28/Feb/2023:19:46:13 -0500] [Client 5] Accepted from localhost (Domain) >D [28/Feb/2023:19:46:13 -0500] [Client 5] Waiting for request. >D [28/Feb/2023:19:46:13 -0500] [Client 5] POST /printers/canon_selphy_cp1200 HTTP/1.1 >D [28/Feb/2023:19:46:13 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" >D [28/Feb/2023:19:46:13 -0500] [Client 5] Read: status=200, state=6 >D [28/Feb/2023:19:46:13 -0500] [Client 5] No authentication data provided. >D [28/Feb/2023:19:46:13 -0500] [Client 5] 2.0 Print-Job 1 >D [28/Feb/2023:19:46:13 -0500] Print-Job ipp://localhost:631/printers/canon_selphy_cp1200 >D [28/Feb/2023:19:46:13 -0500] [Job ???] Auto-typing file... >I [28/Feb/2023:19:46:13 -0500] [Job ???] Request file type is application/vnd.cups-pdf-banner. >D [28/Feb/2023:19:46:13 -0500] cupsdMarkDirty(---J-) >D [28/Feb/2023:19:46:13 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" >D [28/Feb/2023:19:46:13 -0500] [Job 477] Applying default options... >D [28/Feb/2023:19:46:13 -0500] add_job: requesting-user-name="root" >D [28/Feb/2023:19:46:13 -0500] Adding default job-sheets values "none,none"... >I [28/Feb/2023:19:46:13 -0500] [Job 477] Adding start banner page "none". >D [28/Feb/2023:19:46:13 -0500] Discarding unused job-created event... >D [28/Feb/2023:19:46:13 -0500] cupsdMarkDirty(---J-) >D [28/Feb/2023:19:46:13 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" >I [28/Feb/2023:19:46:13 -0500] [Job 477] Adding end banner page "none". >I [28/Feb/2023:19:46:13 -0500] [Job 477] File of type application/vnd.cups-pdf-banner queued by "root". >D [28/Feb/2023:19:46:13 -0500] [Job 477] hold_until=0 >I [28/Feb/2023:19:46:13 -0500] [Job 477] Queued on "canon_selphy_cp1200" by "root". >D [28/Feb/2023:19:46:13 -0500] [Job 477] time-at-processing=1677631573 >D [28/Feb/2023:19:46:13 -0500] cupsdMarkDirty(---J-) >D [28/Feb/2023:19:46:13 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" >D [28/Feb/2023:19:46:13 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" >D [28/Feb/2023:19:46:13 -0500] Discarding unused printer-state-changed event... >D [28/Feb/2023:19:46:13 -0500] [Job 477] 4 filters for job: >D [28/Feb/2023:19:46:13 -0500] [Job 477] bannertopdf (application/vnd.cups-pdf-banner to application/pdf, cost 32) >D [28/Feb/2023:19:46:13 -0500] [Job 477] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66) >D [28/Feb/2023:19:46:13 -0500] [Job 477] gstoraster (application/vnd.cups-pdf to application/vnd.cups-raster, cost 99) >D [28/Feb/2023:19:46:13 -0500] [Job 477] rastertogutenprint.5.3 (application/vnd.cups-raster to printer/canon_selphy_cp1200, cost 100) >D [28/Feb/2023:19:46:13 -0500] [Job 477] job-sheets=none,none >D [28/Feb/2023:19:46:13 -0500] [Job 477] argv[0]="canon_selphy_cp1200" >D [28/Feb/2023:19:46:13 -0500] [Job 477] argv[1]="477" >D [28/Feb/2023:19:46:13 -0500] [Job 477] argv[2]="root" >D [28/Feb/2023:19:46:13 -0500] [Job 477] argv[3]="Test Page" >D [28/Feb/2023:19:46:13 -0500] [Job 477] argv[4]="1" >D [28/Feb/2023:19:46:13 -0500] [Job 477] argv[5]="job-uuid=urn:uuid:ee086df5-cac2-3a47-5721-e487c1c314d9 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1677631573 time-at-processing=1677631573" >D [28/Feb/2023:19:46:13 -0500] [Job 477] argv[6]="/var/spool/cups/d00477-001" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[0]="CUPS_CACHEDIR=/var/cache/cups" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[1]="CUPS_DATADIR=/usr/share/cups" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[2]="CUPS_DOCROOT=/usr/share/doc/cups" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[3]="CUPS_REQUESTROOT=/var/spool/cups" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[4]="CUPS_SERVERBIN=/usr/lib/cups" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[5]="CUPS_SERVERROOT=/etc/cups" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[6]="CUPS_STATEDIR=/run/cups" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[7]="HOME=/var/spool/cups/tmp" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[9]="SERVER_ADMIN=root@4void" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[10]="SOFTWARE=CUPS/2.4.2" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[11]="TMPDIR=/var/spool/cups/tmp" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[12]="USER=root" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[13]="CUPS_MAX_MESSAGE=2047" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[14]="CUPS_SERVER=/run/cups/cups.sock" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[15]="CUPS_ENCRYPTION=IfRequested" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[16]="IPP_PORT=631" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[17]="CHARSET=utf-8" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[18]="LANG=en_US.UTF-8" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[19]="PPD=/etc/cups/ppd/canon_selphy_cp1200.ppd" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[20]="CONTENT_TYPE=application/vnd.cups-pdf-banner" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[21]="DEVICE_URI=usb://Canon/SELPHY%20CP1200?serial=ZT15110600003044" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[22]="PRINTER_INFO=Canon SELPHY CP1200" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[23]="PRINTER_LOCATION=local" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[24]="PRINTER=canon_selphy_cp1200" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[25]="PRINTER_STATE_REASONS=none" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[26]="CUPS_FILETYPE=document" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[27]="FINAL_CONTENT_TYPE=application/vnd.cups-raster" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[28]="AUTH_I****" >I [28/Feb/2023:19:46:13 -0500] [Job 477] Started filter /usr/lib/cups/filter/bannertopdf (PID 15318) >I [28/Feb/2023:19:46:13 -0500] [Job 477] Started filter /usr/lib/cups/filter/pdftopdf (PID 15319) >I [28/Feb/2023:19:46:13 -0500] [Job 477] Started filter /usr/lib/cups/filter/gstoraster (PID 15320) >I [28/Feb/2023:19:46:13 -0500] [Job 477] Started filter /usr/lib/cups/filter/rastertogutenprint.5.3 (PID 15321) >I [28/Feb/2023:19:46:13 -0500] [Job 477] Started backend /usr/lib/cups/backend/usb (PID 15322) >D [28/Feb/2023:19:46:13 -0500] Discarding unused job-state-changed event... >D [28/Feb/2023:19:46:13 -0500] [Client 5] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/canon_selphy_cp1200) from localhost. >D [28/Feb/2023:19:46:13 -0500] [Client 5] Content-Length: 193 >D [28/Feb/2023:19:46:13 -0500] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >D [28/Feb/2023:19:46:13 -0500] [Client 5] con->http=0x55ad51ac7c50 >D [28/Feb/2023:19:46:13 -0500] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=193, response=0x55ad51aa5580(IPP_STATE_IDLE), pipe_pid=0, file=-1 >D [28/Feb/2023:19:46:13 -0500] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >D [28/Feb/2023:19:46:13 -0500] [Client 5] bytes=0, http_state=0, data_remaining=193 >D [28/Feb/2023:19:46:13 -0500] [Client 5] Flushing write buffer. >D [28/Feb/2023:19:46:13 -0500] [Client 5] New state is HTTP_STATE_WAITING >D [28/Feb/2023:19:46:13 -0500] [Client 5] Waiting for request. >D [28/Feb/2023:19:46:13 -0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files" >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetVariable: REMOTE_USER=\"root\" >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.2\" >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetVariable: REMOTE_USER=\"root\" >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.2\" >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetArray: job_uri[0]=\"/jobs/477\" >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetArray: job_id[0]=\"477\" >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetArray: job_state[0]=\"3\" >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetArray: job_state_message[0]=\"\" >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetArray: job_state_reasons[0]=\"none\" >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetVariable: refresh_page=\"2;URL=/printers/canon_selphy_cp1200\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] Gutenprint: >D [28/Feb/2023:19:46:13 -0500] [Job 477] Gutenprint: ERROR: ***Gutenprint 5.3.4 assertion list != NULL failed! file print-list.c, line 212. Please report this bug! >D [28/Feb/2023:19:46:13 -0500] [Job 477] PID 15321 (/usr/lib/cups/filter/rastertogutenprint.5.3) crashed on signal 6. >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetVariable: TITLE=\"Print Test Page\" >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetVariable: REMOTE_USER=\"root\" >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.2\" >D [28/Feb/2023:19:46:13 -0500] [Client 1] CGI data ready to be sent. >D [28/Feb/2023:19:46:13 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:13 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=15317, file=18 >D [28/Feb/2023:19:46:13 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:13 -0500] [Client 1] Script header: Content-Type: text/html;charset=utf-8 >D [28/Feb/2023:19:46:13 -0500] [Client 1] Script header: >D [28/Feb/2023:19:46:13 -0500] [Client 1] Sending status 200 for CGI. >D [28/Feb/2023:19:46:13 -0500] [Client 1] cupsdSendHeader: code=200, type="(null)", auth_type=0 >D [28/Feb/2023:19:46:13 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:13 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15317, file=18 >D [28/Feb/2023:19:46:13 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:13 -0500] [Client 1] CGI data ready to be sent. >D [28/Feb/2023:19:46:13 -0500] [CGI] cgiSetVariable: PRINTER_NAME=\"canon_selphy_cp1200\" >D [28/Feb/2023:19:46:13 -0500] [Client 5] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >D [28/Feb/2023:19:46:13 -0500] [Client 5] Closing connection. >D [28/Feb/2023:19:46:13 -0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" >D [28/Feb/2023:19:46:13 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:13 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15317, file=18 >D [28/Feb/2023:19:46:13 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:13 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:13 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15317, file=18 >D [28/Feb/2023:19:46:13 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:13 -0500] [Job 477] Loading USB quirks from \"/usr/share/cups/usb\". >D [28/Feb/2023:19:46:13 -0500] [Job 477] Loaded 118 quirks. >D [28/Feb/2023:19:46:13 -0500] [Job 477] Printing on printer with URI: usb://Canon/SELPHY%20CP1200?serial=ZT15110600003044 >D [28/Feb/2023:19:46:13 -0500] [Job 477] pdftopdf: Last filter determined by the PPD: rastertogutenprint.5.3; FINAL_CONTENT_TYPE: application/vnd.cups-raster => pdftopdf will not log pages in page_log. >D [28/Feb/2023:19:46:13 -0500] [Client 1] CGI data ready to be sent. >D [28/Feb/2023:19:46:13 -0500] PID 15317 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. >D [28/Feb/2023:19:46:13 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:13 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15317, file=18 >D [28/Feb/2023:19:46:13 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:13 -0500] [Client 1] Sending 0-length chunk. >D [28/Feb/2023:19:46:13 -0500] [Client 1] Flushing write buffer. >D [28/Feb/2023:19:46:13 -0500] [Client 1] New state is HTTP_STATE_WAITING >D [28/Feb/2023:19:46:13 -0500] [Client 1] Waiting for request. >D [28/Feb/2023:19:46:13 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" >D [28/Feb/2023:19:46:13 -0500] [Job 477] PDF template file doesn\'t have form. It\'s okay. >D [28/Feb/2023:19:46:13 -0500] [Job 477] OUTFORMAT=\"<none>\", so output format will be CUPS/PWG Raster >D [28/Feb/2023:19:46:13 -0500] [Job 477] PID 15318 (/usr/lib/cups/filter/bannertopdf) exited with no errors. >D [28/Feb/2023:19:46:13 -0500] [Job 477] PDF interactive form and annotation flattening done via QPDF >D [28/Feb/2023:19:46:13 -0500] [Job 477] pdftopdf: \"print-scaling\" IPP attribute: auto >D [28/Feb/2023:19:46:13 -0500] [Job 477] pdftopdf: Print scaling mode: Do not scale, center, crop if needed >D [28/Feb/2023:19:46:13 -0500] [Job 477] After Cropping: 299.519989 449.279999 299.519989 449.279999 >D [28/Feb/2023:19:46:13 -0500] [Job 477] PID 15319 (/usr/lib/cups/filter/pdftopdf) exited with no errors. >D [28/Feb/2023:19:46:13 -0500] [Job 477] Color Manager: Calibration Mode/Off >D [28/Feb/2023:19:46:13 -0500] [Job 477] Calling FindDeviceById(cups-canon_selphy_cp1200) >D [28/Feb/2023:19:46:13 -0500] [Job 477] Failed to send: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files >D [28/Feb/2023:19:46:13 -0500] [Job 477] Failed to get find device cups-canon_selphy_cp1200 >D [28/Feb/2023:19:46:13 -0500] [Job 477] Calling FindDeviceById(cups-canon_selphy_cp1200) >D [28/Feb/2023:19:46:13 -0500] [Job 477] Failed to send: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files >D [28/Feb/2023:19:46:13 -0500] [Job 477] Failed to get device cups-canon_selphy_cp1200 >D [28/Feb/2023:19:46:13 -0500] [Job 477] Color Manager: no profiles specified in PPD >D [28/Feb/2023:19:46:13 -0500] Discarding unused job-progress event... >D [28/Feb/2023:19:46:13 -0500] Discarding unused printer-state-changed event... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Color Manager: ICC Profile: None >D [28/Feb/2023:19:46:13 -0500] [Job 477] Ghostscript using Any-Part-of-Pixel method to fill paths. >D [28/Feb/2023:19:46:13 -0500] [Job 477] Ghostscript command line: gs -dQUIET -dSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -dNOMEDIAATTRS -dShowAcroForm -sstdout=%stderr -sOutputFile=%stdout -sDEVICE=cups -r300x300 -dDEVICEWIDTHPOINTS=299 -dDEVICEHEIGHTPOINTS=449 -dcupsBitsPerColor=8 -dcupsColorOrder=0 -dcupsColorSpace=1 -dcupsCompression=1 -scupsPageSizeName=Postcard -I/usr/share/cups/fonts -c \'<</.HWMargins[0.000000 0.000000 0.000000 0.000000] /Margins[0 0]>>setpagedevice\' -f -_ >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[0]=\"CUPS_CACHEDIR=/var/cache/cups\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[1]=\"CUPS_DATADIR=/usr/share/cups\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[2]=\"CUPS_DOCROOT=/usr/share/doc/cups\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[3]=\"CUPS_REQUESTROOT=/var/spool/cups\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[4]=\"CUPS_SERVERBIN=/usr/lib/cups\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[5]=\"CUPS_SERVERROOT=/etc/cups\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[6]=\"CUPS_STATEDIR=/run/cups\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[7]=\"HOME=/var/spool/cups/tmp\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[8]=\"PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[9]=\"SERVER_ADMIN=root@4void\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[10]=\"SOFTWARE=CUPS/2.4.2\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[11]=\"TMPDIR=/var/spool/cups/tmp\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[12]=\"USER=root\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[13]=\"CUPS_MAX_MESSAGE=2047\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[14]=\"CUPS_SERVER=/run/cups/cups.sock\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[15]=\"CUPS_ENCRYPTION=IfRequested\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[16]=\"IPP_PORT=631\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[17]=\"CHARSET=utf-8\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[18]=\"LANG=en_US.UTF-8\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[19]=\"PPD=/etc/cups/ppd/canon_selphy_cp1200.ppd\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[20]=\"CONTENT_TYPE=application/vnd.cups-pdf-banner\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[21]=\"DEVICE_URI=usb://Canon/SELPHY%20CP1200?serial=ZT15110600003044\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[22]=\"PRINTER_INFO=Canon SELPHY CP1200\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[23]=\"PRINTER_LOCATION=local\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[24]=\"PRINTER=canon_selphy_cp1200\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[25]=\"PRINTER_STATE_REASONS=none\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[26]=\"CUPS_FILETYPE=document\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[27]=\"FINAL_CONTENT_TYPE=application/vnd.cups-raster\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] envp[28]=\"AUTH_INFO_REQUIRED=none\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] libusb_get_device_list=39 >D [28/Feb/2023:19:46:13 -0500] [Job 477] STATE: +connecting-to-device >D [28/Feb/2023:19:46:13 -0500] cupsdMarkDirty(---J-) >D [28/Feb/2023:19:46:13 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files" >D [28/Feb/2023:19:46:13 -0500] Discarding unused printer-state-changed event... >D [28/Feb/2023:19:46:13 -0500] [Job 477] STATE: -connecting-to-device >D [28/Feb/2023:19:46:13 -0500] cupsdMarkDirty(---J-) >D [28/Feb/2023:19:46:13 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files" >D [28/Feb/2023:19:46:13 -0500] Discarding unused printer-state-changed event... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Device protocol: 2 >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Sending data to printer. >D [28/Feb/2023:19:46:13 -0500] Discarding unused job-progress event... >D [28/Feb/2023:19:46:13 -0500] Discarding unused printer-state-changed event... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Sent 0 bytes... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Start rendering... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Processing page 1... >D [28/Feb/2023:19:46:13 -0500] Discarding unused job-progress event... >D [28/Feb/2023:19:46:13 -0500] Discarding unused printer-state-changed event... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >E [28/Feb/2023:19:46:13 -0500] [Job 477] Unable to open raster stream - : Broken pipe >D [28/Feb/2023:19:46:13 -0500] Discarding unused job-progress event... >D [28/Feb/2023:19:46:13 -0500] Discarding unused printer-state-changed event... >D [28/Feb/2023:19:46:13 -0500] [Job 477] **** Error: Page drawing error occurred. >D [28/Feb/2023:19:46:13 -0500] [Job 477] Could not draw this page at all, page will be missing in the output. >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Rendering completed >D [28/Feb/2023:19:46:13 -0500] Discarding unused job-progress event... >D [28/Feb/2023:19:46:13 -0500] Discarding unused printer-state-changed event... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] PID 15320 (/usr/lib/cups/filter/gstoraster) exited with no errors. >D [28/Feb/2023:19:46:13 -0500] [Job 477] Waiting for read thread to exit... >D [28/Feb/2023:19:46:13 -0500] [Job 477] Read 12 bytes of back-channel data... >D [28/Feb/2023:19:46:13 -0500] [Job 477] PID 15322 (/usr/lib/cups/backend/usb) exited with no errors. >D [28/Feb/2023:19:46:13 -0500] Discarding unused job-state-changed event... >E [28/Feb/2023:19:46:13 -0500] [Job 477] Job stopped due to filter errors; please consult the /var/log/cups/error_log file for details. >D [28/Feb/2023:19:46:13 -0500] cupsdMarkDirty(---J-) >D [28/Feb/2023:19:46:13 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >D [28/Feb/2023:19:46:13 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >D [28/Feb/2023:19:46:13 -0500] Discarding unused printer-state-changed event... >D [28/Feb/2023:19:46:13 -0500] [Job 477] The following messages were recorded from 19:46:13 to 19:46:13 >D [28/Feb/2023:19:46:13 -0500] [Job 477] iSerialNumber=\"ZT15110600003044\" >D [28/Feb/2023:19:46:13 -0500] [Job 477] Printer found with device ID: MFG:Canon;CMD:Raster3;MDL:SELPHY CP1200;CLS:PRINTER;DES:Canon SELPHY CP1200;VER:1.00;CID:CA_YCC_ICP; Device URI: usb://Canon/SELPHY%20CP1200?serial=ZT15110600003044 >D [28/Feb/2023:19:46:13 -0500] [Job 477] End of messages >D [28/Feb/2023:19:46:13 -0500] [Job 477] printer-state=3(idle) >D [28/Feb/2023:19:46:13 -0500] [Job 477] printer-state-message="Rendering completed" >D [28/Feb/2023:19:46:13 -0500] [Job 477] printer-state-reasons=none >D [28/Feb/2023:19:46:14 -0500] [Job 477] Unloading... >D [28/Feb/2023:19:46:15 -0500] [Client 1] GET /printers/canon_selphy_cp1200 HTTP/1.1 >D [28/Feb/2023:19:46:15 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files" >D [28/Feb/2023:19:46:15 -0500] [Client 1] Read: status=200, state=3 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Authorized as "root" using Basic. >D [28/Feb/2023:19:46:15 -0500] [Client 1] Processing GET /printers/canon_selphy_cp1200 >D [28/Feb/2023:19:46:15 -0500] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/doc/cups" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[3] = "CUPS_REQUESTROOT=/var/spool/cups" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[4] = "CUPS_SERVERBIN=/usr/lib/cups" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[5] = "CUPS_SERVERROOT=/etc/cups" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[6] = "CUPS_STATEDIR=/run/cups" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[7] = "HOME=/var/spool/cups/tmp" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[8] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[9] = "SERVER_ADMIN=root@4void" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[10] = "SOFTWARE=CUPS/2.4.2" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[11] = "TMPDIR=/var/spool/cups/tmp" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[12] = "USER=root" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[13] = "CUPS_MAX_MESSAGE=2047" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[14] = "CUPS_SERVER=/run/cups/cups.sock" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[16] = "IPP_PORT=631" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[17] = "AUTH_TYPE=Basic" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[18] = "LANG=en_US.UTF8" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[19] = "REDIRECT_STATUS=1" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[21] = "SERVER_NAME=localhost" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[22] = "SERVER_PORT=631" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[23] = "REMOTE_ADDR=[v1.::1]" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[24] = "REMOTE_HOST=localhost" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[25] = "SCRIPT_NAME=/printers/canon_selphy_cp1200" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/doc/cups/printers/canon_selphy_cp1200" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[27] = "PATH_INFO=/canon_selphy_cp1200" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[28] = "REMOTE_USER=root" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[30] = "HTTP_COOKIE=org.cups.sid=dc588e31213b6af2038380c9852a5026" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/110.0.0.0 Safari/537.36" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[32] = "HTTP_REFERER=http://localhost:631/printers/canon_selphy_cp1200" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[33] = "REQUEST_METHOD=GET" >D [28/Feb/2023:19:46:15 -0500] [CGI] envp[34] = "QUERY_STRING=" >D [28/Feb/2023:19:46:15 -0500] cupsdAddCert: Adding certificate for PID 15332 >D [28/Feb/2023:19:46:15 -0500] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 15332) >I [28/Feb/2023:19:46:15 -0500] [Client 1] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=15332, file=18) >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [CGI] org.cups.sid cookie is \"dc588e31213b6af2038380c9852a5026\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: SECTION=\"printers\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: PRINTER_NAME=\"canon_selphy_cp1200\" >D [28/Feb/2023:19:46:15 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" >D [28/Feb/2023:19:46:15 -0500] [Client 6] Server address is "/run/cups/cups.sock". >D [28/Feb/2023:19:46:15 -0500] [Client 6] Accepted from localhost (Domain) >D [28/Feb/2023:19:46:15 -0500] [Client 6] Waiting for request. >D [28/Feb/2023:19:46:15 -0500] [Client 6] POST / HTTP/1.1 >D [28/Feb/2023:19:46:15 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" >D [28/Feb/2023:19:46:15 -0500] [Client 6] Read: status=200, state=6 >D [28/Feb/2023:19:46:15 -0500] [Client 6] No authentication data provided. >D [28/Feb/2023:19:46:15 -0500] [Client 6] 2.0 CUPS-Get-Default 1 >D [28/Feb/2023:19:46:15 -0500] CUPS-Get-Default >D [28/Feb/2023:19:46:15 -0500] CUPS-Get-Default client-error-not-found: No default printer. >D [28/Feb/2023:19:46:15 -0500] [Client 6] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost. >D [28/Feb/2023:19:46:15 -0500] [Client 6] Content-Length: 113 >D [28/Feb/2023:19:46:15 -0500] [Client 6] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >D [28/Feb/2023:19:46:15 -0500] [Client 6] con->http=0x55ad51acd360 >D [28/Feb/2023:19:46:15 -0500] [Client 6] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x55ad51aa5580(IPP_STATE_DATA), pipe_pid=0, file=-1 >D [28/Feb/2023:19:46:15 -0500] [Client 6] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >D [28/Feb/2023:19:46:15 -0500] [Client 6] bytes=0, http_state=0, data_remaining=113 >D [28/Feb/2023:19:46:15 -0500] [Client 6] Flushing write buffer. >D [28/Feb/2023:19:46:15 -0500] [Client 6] New state is HTTP_STATE_WAITING >D [28/Feb/2023:19:46:15 -0500] [Client 6] Waiting for request. >D [28/Feb/2023:19:46:15 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" >D [28/Feb/2023:19:46:15 -0500] [CGI] show_printer(http=0x559bea92bea0, printer=\"canon_selphy_cp1200\") >D [28/Feb/2023:19:46:15 -0500] [Client 6] POST / HTTP/1.1 >D [28/Feb/2023:19:46:15 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" >D [28/Feb/2023:19:46:15 -0500] [Client 6] Read: status=200, state=6 >D [28/Feb/2023:19:46:15 -0500] [Client 6] No authentication data provided. >D [28/Feb/2023:19:46:15 -0500] [Client 6] 2.0 Get-Printer-Attributes 2 >D [28/Feb/2023:19:46:15 -0500] Get-Printer-Attributes ipp://localhost/printers/canon_selphy_cp1200 >D [28/Feb/2023:19:46:15 -0500] [Client 6] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/canon_selphy_cp1200) from localhost. >D [28/Feb/2023:19:46:15 -0500] [Client 6] Content-Length: 692 >D [28/Feb/2023:19:46:15 -0500] [Client 6] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >D [28/Feb/2023:19:46:15 -0500] [Client 6] con->http=0x55ad51acd360 >D [28/Feb/2023:19:46:15 -0500] [Client 6] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=692, response=0x55ad51a984d0(IPP_STATE_DATA), pipe_pid=0, file=-1 >D [28/Feb/2023:19:46:15 -0500] [Client 6] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >D [28/Feb/2023:19:46:15 -0500] [Client 6] bytes=0, http_state=0, data_remaining=692 >D [28/Feb/2023:19:46:15 -0500] [Client 6] Flushing write buffer. >D [28/Feb/2023:19:46:15 -0500] [Client 6] New state is HTTP_STATE_WAITING >D [28/Feb/2023:19:46:15 -0500] [Client 6] Waiting for request. >D [28/Feb/2023:19:46:15 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: REMOTE_USER=\"root\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.2\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: REMOTE_USER=\"root\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.2\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: printer_is_shared[0]=\"1\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: printer_state[0]=\"3\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/canon_selphy_cp1200\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: printer_name[0]=\"canon_selphy_cp1200\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: printer_location[0]=\"local\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: printer_info[0]=\"Canon SELPHY CP1200\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: device_uri[0]=\"usb://Canon/SELPHY%20CP1200?serial=ZT15110600003044\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: color_supported[0]=\"1\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: printer_make_and_model[0]=\"Canon SELPHY CP1200 - CUPS+Gutenprint v5.3.4 Simplified\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: media_default[0]=\"custom_105.66x158.5mm_105.66x158.5mm\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: sides_supported[0]=\"one-sided\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: sides_default[0]=\"one-sided\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: printer_commands[0]=\"none\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"1\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: TITLE=\"canon_selphy_cp1200\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: REMOTE_USER=\"root\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.2\" >D [28/Feb/2023:19:46:15 -0500] [Client 1] CGI data ready to be sent. >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [Client 1] Script header: Content-Type: text/html;charset=utf-8 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Script header: >D [28/Feb/2023:19:46:15 -0500] [Client 1] Sending status 200 for CGI. >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdSendHeader: code=200, type="(null)", auth_type=0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [Client 1] CGI data ready to be sent. >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [CGI] Regular expression \".*Clean.*\" >D [28/Feb/2023:19:46:15 -0500] [CGI] Regular expression \".*PrintSelfTestPage.*\" >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [Client 1] CGI data ready to be sent. >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [Client 1] CGI data ready to be sent. >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [Client 6] POST / HTTP/1.1 >D [28/Feb/2023:19:46:15 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" >D [28/Feb/2023:19:46:15 -0500] [Client 6] Read: status=200, state=6 >D [28/Feb/2023:19:46:15 -0500] [Client 6] No authentication data provided. >D [28/Feb/2023:19:46:15 -0500] [Client 6] 2.0 Get-Jobs 3 >D [28/Feb/2023:19:46:15 -0500] Get-Jobs ipp://localhost:631/printers/canon_selphy_cp1200 >D [28/Feb/2023:19:46:15 -0500] [Job 477] Loading attributes... >D [28/Feb/2023:19:46:15 -0500] [Client 6] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/canon_selphy_cp1200) from localhost. >D [28/Feb/2023:19:46:15 -0500] [Client 6] Content-Length: 390 >D [28/Feb/2023:19:46:15 -0500] [Client 6] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >D [28/Feb/2023:19:46:15 -0500] [Client 6] con->http=0x55ad51acd360 >D [28/Feb/2023:19:46:15 -0500] [Client 6] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=390, response=0x55ad51a9aa70(IPP_STATE_DATA), pipe_pid=0, file=-1 >D [28/Feb/2023:19:46:15 -0500] [Client 6] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >D [28/Feb/2023:19:46:15 -0500] [Client 6] bytes=0, http_state=0, data_remaining=390 >D [28/Feb/2023:19:46:15 -0500] [Client 6] Flushing write buffer. >D [28/Feb/2023:19:46:15 -0500] [Client 6] New state is HTTP_STATE_WAITING >D [28/Feb/2023:19:46:15 -0500] [Client 6] Waiting for request. >D [28/Feb/2023:19:46:15 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiClearVariables called. >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: SECTION=\"printers\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: TOTAL=\"1\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: REMOTE_USER=\"root\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.2\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: job_preserved[0]=\"1\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: job_printer_name[0]=\"canon_selphy_cp1200\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: job_printer_uri[0]=\"/printers/canon_selphy_cp1200\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: time_at_completed[0]=\"novalue\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: time_at_creation[0]=\"Tue 28 Feb 2023 07:46:13 PM EST\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: time_at_processing[0]=\"Tue 28 Feb 2023 07:46:13 PM EST\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: job_id[0]=\"477\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: job_state[0]=\"6\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: job_impressions_completed[0]=\"0\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: job_k_octets[0]=\"1\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: job_hold_until[0]=\"no-hold\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetArray: job_printer_state_message[0]=\"Filter failed\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: PRINTER_NAME=\"canon_selphy_cp1200\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/canon_selphy_cp1200\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: THISURL=\"/printers/canon_selphy_cp1200\" >D [28/Feb/2023:19:46:15 -0500] [CGI] cgiSetVariable: SEARCH_DEST=\"canon_selphy_cp1200\" >D [28/Feb/2023:19:46:15 -0500] [Client 1] CGI data ready to be sent. >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [Client 1] CGI data ready to be sent. >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [Client 1] CGI data ready to be sent. >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [Client 1] CGI data ready to be sent. >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [Client 1] CGI data ready to be sent. >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [Client 1] CGI data ready to be sent. >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [Client 6] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >D [28/Feb/2023:19:46:15 -0500] [Client 6] Closing connection. >D [28/Feb/2023:19:46:15 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [Client 1] CGI data ready to be sent. >D [28/Feb/2023:19:46:15 -0500] [Client 1] con->http=0x55ad51ac0fb0 >D [28/Feb/2023:19:46:15 -0500] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=15332, file=18 >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for CGI data. >D [28/Feb/2023:19:46:15 -0500] [Client 1] Sending 0-length chunk. >D [28/Feb/2023:19:46:15 -0500] [Client 1] Flushing write buffer. >D [28/Feb/2023:19:46:15 -0500] [Client 1] New state is HTTP_STATE_WAITING >D [28/Feb/2023:19:46:15 -0500] [Client 1] Waiting for request. >D [28/Feb/2023:19:46:15 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" >D [28/Feb/2023:19:46:15 -0500] PID 15332 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. |
