search

apple / cups

Apple CUPS Sources
https://www.cups.org
Apache License 2.0
1.88k stars 462 forks source link

Canon LBP 2900 filter failed error #6119

Open copetogo opened 1 year ago

copetogo commented 1 year ago

i get a filter failed error attaching the error log below

E [13/May/2023:19:01:18 +0000] [Job 100] Job stopped due to filter errors; please consult the error_log file for details. D [13/May/2023:19:01:18 +0000] [Job 100] The following messages were recorded from 19:01:09 to 19:01:18 D [13/May/2023:19:01:18 +0000] [Job 100] Adding start banner page "none". D [13/May/2023:19:01:18 +0000] [Job 100] Adding end banner page "none". D [13/May/2023:19:01:18 +0000] [Job 100] File of type application/vnd.cups-pdf-banner queued by "pi". D [13/May/2023:19:01:18 +0000] [Job 100] hold_until=0 D [13/May/2023:19:01:18 +0000] [Job 100] Queued on "Canon_LBP2900" by "pi". D [13/May/2023:19:01:18 +0000] [Job 100] time-at-processing=1684004469 D [13/May/2023:19:01:18 +0000] [Job 100] 4 filters for job: D [13/May/2023:19:01:18 +0000] [Job 100] bannertopdf (application/vnd.cups-pdf-banner to application/pdf, cost 32) D [13/May/2023:19:01:18 +0000] [Job 100] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66) D [13/May/2023:19:01:18 +0000] [Job 100] pdftops (application/vnd.cups-pdf to application/vnd.cups-postscript, cost 100) D [13/May/2023:19:01:18 +0000] [Job 100] pstocapt (application/vnd.cups-postscript to printer/Canon_LBP2900, cost 0) D [13/May/2023:19:01:18 +0000] [Job 100] job-sheets=none,none D [13/May/2023:19:01:18 +0000] [Job 100] argv[0]="Canon_LBP2900" D [13/May/2023:19:01:18 +0000] [Job 100] argv[1]="100" D [13/May/2023:19:01:18 +0000] [Job 100] argv[2]="pi" D [13/May/2023:19:01:18 +0000] [Job 100] argv[3]="Test Page" D [13/May/2023:19:01:18 +0000] [Job 100] argv[4]="1" D [13/May/2023:19:01:18 +0000] [Job 100] argv[5]="job-uuid=urn:uuid:5b71cd74-7960-3123-6f81-3d4c9bfef23b job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1684004469 time-at-processing=1684004469" D [13/May/2023:19:01:18 +0000] [Job 100] argv[6]="/var/spool/cups/d00100-001" D [13/May/2023:19:01:18 +0000] [Job 100] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [13/May/2023:19:01:18 +0000] [Job 100] envp[1]="CUPS_DATADIR=/usr/share/cups" D [13/May/2023:19:01:18 +0000] [Job 100] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root" D [13/May/2023:19:01:18 +0000] [Job 100] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [13/May/2023:19:01:18 +0000] [Job 100] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [13/May/2023:19:01:18 +0000] [Job 100] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [13/May/2023:19:01:18 +0000] [Job 100] envp[6]="CUPS_SERVERROOT=/etc/cups" D [13/May/2023:19:01:18 +0000] [Job 100] envp[7]="CUPS_STATEDIR=/var/run/cups" D [13/May/2023:19:01:18 +0000] [Job 100] envp[8]="HOME=/var/spool/cups/tmp" D [13/May/2023:19:01:18 +0000] [Job 100] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [13/May/2023:19:01:18 +0000] [Job 100] envp[10]="SERVER_ADMIN=root@raspberrypi" D [13/May/2023:19:01:18 +0000] [Job 100] envp[11]="SOFTWARE=CUPS/2.2.1" D [13/May/2023:19:01:18 +0000] [Job 100] envp[12]="TMPDIR=/var/spool/cups/tmp" D [13/May/2023:19:01:18 +0000] [Job 100] envp[13]="USER=root" D [13/May/2023:19:01:18 +0000] [Job 100] envp[14]="CUPS_MAX_MESSAGE=2047" D [13/May/2023:19:01:18 +0000] [Job 100] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock" D [13/May/2023:19:01:18 +0000] [Job 100] envp[16]="CUPS_ENCRYPTION=IfRequested" D [13/May/2023:19:01:18 +0000] [Job 100] envp[17]="IPP_PORT=631" D [13/May/2023:19:01:18 +0000] [Job 100] envp[18]="CHARSET=utf-8" D [13/May/2023:19:01:18 +0000] [Job 100] envp[19]="LANG=en_US.UTF-8" D [13/May/2023:19:01:18 +0000] [Job 100] envp[20]="PPD=/etc/cups/ppd/Canon_LBP2900.ppd" D [13/May/2023:19:01:18 +0000] [Job 100] envp[21]="RIP_MAX_CACHE=128m" D [13/May/2023:19:01:18 +0000] [Job 100] envp[22]="CONTENT_TYPE=application/vnd.cups-pdf-banner" D [13/May/2023:19:01:18 +0000] [Job 100] envp[23]="DEVICE_URI=usb://Canon/LBP2900?serial=0000C1D2K6cC" D [13/May/2023:19:01:18 +0000] [Job 100] envp[24]="PRINTER_INFO=Canon LBP2900" D [13/May/2023:19:01:18 +0000] [Job 100] envp[25]="PRINTER_LOCATION=" D [13/May/2023:19:01:18 +0000] [Job 100] envp[26]="PRINTER=Canon_LBP2900" D [13/May/2023:19:01:18 +0000] [Job 100] envp[27]="PRINTER_STATE_REASONS=none" D [13/May/2023:19:01:18 +0000] [Job 100] envp[28]="CUPS_FILETYPE=document" D [13/May/2023:19:01:18 +0000] [Job 100] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript" D [13/May/2023:19:01:18 +0000] [Job 100] envp[30]="AUTH_I****" D [13/May/2023:19:01:18 +0000] [Job 100] Started filter /usr/lib/cups/filter/bannertopdf (PID 3270) D [13/May/2023:19:01:18 +0000] [Job 100] Started filter /usr/lib/cups/filter/pdftopdf (PID 3271) D [13/May/2023:19:01:18 +0000] [Job 100] Started filter /usr/lib/cups/filter/pdftops (PID 3272) D [13/May/2023:19:01:18 +0000] [Job 100] Started filter /usr/lib/cups/filter/pstocapt (PID 3273) D [13/May/2023:19:01:18 +0000] [Job 100] Started backend /usr/lib/cups/backend/usb (PID 3274) D [13/May/2023:19:01:18 +0000] [Job 100] PID 3273 (/usr/lib/cups/filter/pstocapt) stopped with status 108 (Exec format error) D [13/May/2023:19:01:18 +0000] [Job 100] Hint: Try setting the LogLevel to "debug" to find out more. D [13/May/2023:19:01:18 +0000] [Job 100] execv failed: Exec format error D [13/May/2023:19:01:18 +0000] [Job 100] pdftops - copying to temp print file \"/var/spool/cups/tmp/00cc8646bdac5\" D [13/May/2023:19:01:18 +0000] [Job 100] Loading USB quirks from \"/usr/share/cups/usb\". D [13/May/2023:19:01:18 +0000] [Job 100] Loaded 133 quirks. D [13/May/2023:19:01:18 +0000] [Job 100] Printing on printer with URI: usb://Canon/LBP2900?serial=0000C1D2K6cC D [13/May/2023:19:01:18 +0000] [Job 100] pdftopdf: Last filter determined by the PPD: pstocapt; FINAL_CONTENT_TYPE: application/vnd.cups-postscript => pdftopdf will not log pages in page_log. D [13/May/2023:19:01:18 +0000] [Job 100] libusb_get_device_list=2 D [13/May/2023:19:01:18 +0000] [Job 100] STATE: +connecting-to-device D [13/May/2023:19:01:18 +0000] [Job 100] STATE: -connecting-to-device D [13/May/2023:19:01:18 +0000] [Job 100] Printer found with device ID: MFG:Canon;MDL:LBP2900;CMD:CAPT;VER:2.1;CLS:PRINTER;DES:Canon LBP2900 Device URI: usb://Canon/LBP2900?serial=0000C1D2K6cC D [13/May/2023:19:01:18 +0000] [Job 100] Device protocol: 2 D [13/May/2023:19:01:18 +0000] [Job 100] Sending data to printer. D [13/May/2023:19:01:18 +0000] [Job 100] Sent 0 bytes... D [13/May/2023:19:01:18 +0000] [Job 100] Waiting for read thread to exit... D [13/May/2023:19:01:18 +0000] [Job 100] PDF template file doesn\'t have form. It\'s okay. D [13/May/2023:19:01:18 +0000] [Job 100] PID 3270 (/usr/lib/cups/filter/bannertopdf) exited with no errors. D [13/May/2023:19:01:18 +0000] [Job 100] PID 3271 (/usr/lib/cups/filter/pdftopdf) exited with no errors. D [13/May/2023:19:01:18 +0000] [Job 100] Printer make and model: Canon LBP2900 D [13/May/2023:19:01:18 +0000] [Job 100] Running command line for pstops: pstops 100 pi \'Test Page\' 1 \'job-uuid=urn:uuid:5b71cd74-7960-3123-6f81-3d4c9bfef23b job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1684004469 time-at-processing=1684004469\' D [13/May/2023:19:01:18 +0000] [Job 100] Using image rendering resolution 600 dpi D [13/May/2023:19:01:18 +0000] [Job 100] Running command line for gs: gs -q -dNOPAUSE -dBATCH -dSAFER -dNOMEDIAATTRS -sDEVICE=ps2write -sOUTPUTFILE=%stdout -dLanguageLevel=3 -r600 -dCompressFonts=false -dNoT3CCITT -dNOINTERPOLATE -c \'save pop\' -f /var/spool/cups/tmp/00cc8646bdac5 D [13/May/2023:19:01:18 +0000] [Job 100] Started filter gs (PID 3282) D [13/May/2023:19:01:18 +0000] [Job 100] Started filter pstops (PID 3283) D [13/May/2023:19:01:18 +0000] [Job 100] Page = 595x842; 14,14 to 581,828 D [13/May/2023:19:01:18 +0000] [Job 100] slow_collate=0, slow_duplex=0, slow_order=0 D [13/May/2023:19:01:18 +0000] [Job 100] Before copy_comments - %!PS-Adobe-3.0 D [13/May/2023:19:01:18 +0000] [Job 100] %!PS-Adobe-3.0 D [13/May/2023:19:01:18 +0000] [Job 100] %%BoundingBox: 0 0 595 842 D [13/May/2023:19:01:18 +0000] [Job 100] %%HiResBoundingBox: 0 0 595.00 842.00 D [13/May/2023:19:01:18 +0000] [Job 100] %%Creator: GPL Ghostscript 926 (ps2write) D [13/May/2023:19:01:18 +0000] [Job 100] %%LanguageLevel: 2 D [13/May/2023:19:01:18 +0000] [Job 100] %%CreationDate: D:20230513190112Z00\'00\' D [13/May/2023:19:01:18 +0000] [Job 100] %%Pages: 1 D [13/May/2023:19:01:18 +0000] [Job 100] %%EndComments D [13/May/2023:19:01:18 +0000] [Job 100] Before copy_prolog - %%BeginProlog D [13/May/2023:19:01:18 +0000] [Job 100] Before copy_setup - %%Page: 1 1 D [13/May/2023:19:01:18 +0000] [Job 100] Before page loop - %%Page: 1 1 D [13/May/2023:19:01:18 +0000] [Job 100] Copying page 1... D [13/May/2023:19:01:18 +0000] [Job 100] pagew = 566.7, pagel = 813.7 D [13/May/2023:19:01:18 +0000] [Job 100] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842 D [13/May/2023:19:01:18 +0000] [Job 100] PageLeft = 14.2, PageRight = 580.8 D [13/May/2023:19:01:18 +0000] [Job 100] PageTop = 827.8, PageBottom = 14.2 D [13/May/2023:19:01:18 +0000] [Job 100] PageWidth = 595.0, PageLength = 842.0 D [13/May/2023:19:01:18 +0000] [Job 100] Wrote 1 pages... D [13/May/2023:19:01:18 +0000] [Job 100] PID 3283 (pstops) exited with no errors. D [13/May/2023:19:01:18 +0000] [Job 100] PID 3282 (gs) exited with no errors. D [13/May/2023:19:01:18 +0000] [Job 100] PID 3272 (/usr/lib/cups/filter/pdftops) exited with no errors. D [13/May/2023:19:01:18 +0000] [Job 100] Read thread still active, aborting the pending read... D [13/May/2023:19:01:18 +0000] [Job 100] PID 3274 (/usr/lib/cups/backend/usb) exited with no errors. D [13/May/2023:19:01:18 +0000] [Job 100] End of messages D [13/May/2023:19:01:18 +0000] [Job 100] printer-state=3(idle) D [13/May/2023:19:01:18 +0000] [Job 100] printer-state-message="Sending data to printer." D [13/May/2023:19:01:18 +0000] [Job 100] printer-state-reasons=none

JunelanMe commented 2 months ago

I get same error with you. Do you have any idea ? I use the Canon LBP 7018C Here is my log

D [25/May/2024:07:49:55 +0000] [Client 1] POST /printers/Canon_7018C HTTP/1.1 D [25/May/2024:07:49:55 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [25/May/2024:07:49:55 +0000] [Client 1] Read: status=200, state=6 D [25/May/2024:07:49:55 +0000] [Client 1] Authorized as "admin" using Basic. D [25/May/2024:07:49:55 +0000] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [25/May/2024:07:49:55 +0000] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [25/May/2024:07:49:55 +0000] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [25/May/2024:07:49:55 +0000] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root" D [25/May/2024:07:49:55 +0000] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [25/May/2024:07:49:55 +0000] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [25/May/2024:07:49:55 +0000] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [25/May/2024:07:49:55 +0000] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [25/May/2024:07:49:55 +0000] [CGI] envp[7] = "CUPS_STATEDIR=/run/cups" D [25/May/2024:07:49:55 +0000] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [25/May/2024:07:49:55 +0000] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [25/May/2024:07:49:55 +0000] [CGI] envp[10] = "SERVER_ADMIN=root@f1d8321aea9d" D [25/May/2024:07:49:55 +0000] [CGI] envp[11] = "SOFTWARE=CUPS/2.2.10" D [25/May/2024:07:49:55 +0000] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [25/May/2024:07:49:55 +0000] [CGI] envp[13] = "USER=root" D [25/May/2024:07:49:55 +0000] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047" D [25/May/2024:07:49:55 +0000] [CGI] envp[15] = "CUPS_SERVER=/run/cups/cups.sock" D [25/May/2024:07:49:55 +0000] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested" D [25/May/2024:07:49:55 +0000] [CGI] envp[17] = "IPP_PORT=631" D [25/May/2024:07:49:55 +0000] [CGI] envp[18] = "AUTH_TYPE=Basic" D [25/May/2024:07:49:55 +0000] [CGI] envp[19] = "LANG=en_US.UTF8" D [25/May/2024:07:49:55 +0000] [CGI] envp[20] = "REDIRECT_STATUS=1" D [25/May/2024:07:49:55 +0000] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1" D [25/May/2024:07:49:55 +0000] [CGI] envp[22] = "SERVER_NAME=192.168.3.193" D [25/May/2024:07:49:55 +0000] [CGI] envp[23] = "SERVER_PORT=631" D [25/May/2024:07:49:55 +0000] [CGI] envp[24] = "REMOTE_ADDR=192.168.3.189" D [25/May/2024:07:49:55 +0000] [CGI] envp[25] = "REMOTE_HOST=192.168.3.189" D [25/May/2024:07:49:55 +0000] [CGI] envp[26] = "SCRIPT_NAME=/printers/Canon_7018C" D [25/May/2024:07:49:55 +0000] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/printers/Canon_7018C" D [25/May/2024:07:49:55 +0000] [CGI] envp[28] = "PATH_INFO=/Canon_7018C" D [25/May/2024:07:49:55 +0000] [CGI] envp[29] = "REMOTE_USER=admin" D [25/May/2024:07:49:55 +0000] [CGI] envp[30] = "SERVER_PROTOCOL=HTTP/1.1" D [25/May/2024:07:49:55 +0000] [CGI] envp[31] = "HTTP_COOKIE=org.cups.sid=aa3aec99084389badfd1f2ce0e4c69ef" D [25/May/2024:07:49:55 +0000] [CGI] envp[32] = "HTTP_USER_AGENT=Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/125.0.0.0 Safari/537.36" D [25/May/2024:07:49:55 +0000] [CGI] envp[33] = "HTTP_REFERER=http://192.168.3.193:631/printers/Canon_7018C" D [25/May/2024:07:49:55 +0000] [CGI] envp[34] = "REQUEST_METHOD=POST" D [25/May/2024:07:49:55 +0000] [CGI] envp[35] = "CONTENT_LENGTH=64" D [25/May/2024:07:49:55 +0000] [CGI] envp[36] = "CONTENT_TYPE=application/x-www-form-urlencoded" D [25/May/2024:07:49:55 +0000] cupsdAddCert: Adding certificate for PID 53 D [25/May/2024:07:49:55 +0000] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 53) I [25/May/2024:07:49:55 +0000] [Client 1] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=53, file=13) D [25/May/2024:07:49:55 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:49:55 +0000] [CGI] org.cups.sid cookie is \"aa3aec99084389badfd1f2ce0e4c69ef\" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetVariable: org.cups.sid=\"aa3aec99084389badfd1f2ce0e4c69ef\" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetVariable: OP=\"print-test-page\" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetVariable: SECTION=\"printers\" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetVariable: PRINTER_NAME=\"Canon_7018C\" D [25/May/2024:07:49:55 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [25/May/2024:07:49:55 +0000] [Client 26] Server address is "/run/cups/cups.sock". D [25/May/2024:07:49:55 +0000] [Client 26] Accepted from localhost (Domain) D [25/May/2024:07:49:55 +0000] [Client 26] Waiting for request. D [25/May/2024:07:49:55 +0000] [Client 26] POST /printers/Canon_7018C HTTP/1.1 D [25/May/2024:07:49:55 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [25/May/2024:07:49:55 +0000] [Client 26] Read: status=200, state=6 D [25/May/2024:07:49:55 +0000] [Client 26] No authentication data provided. D [25/May/2024:07:49:55 +0000] [Client 26] 2.0 Print-Job 1 D [25/May/2024:07:49:55 +0000] Print-Job ipp://localhost:631/printers/Canon_7018C D [25/May/2024:07:49:55 +0000] [Job ???] Auto-typing file... I [25/May/2024:07:49:55 +0000] [Job ???] Request file type is application/vnd.cups-pdf-banner. D [25/May/2024:07:49:55 +0000] cupsdMarkDirty(---J-) D [25/May/2024:07:49:55 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients" D [25/May/2024:07:49:55 +0000] [Job 5] Applying default options... D [25/May/2024:07:49:55 +0000] add_job: requesting-user-name="admin" D [25/May/2024:07:49:55 +0000] Adding default job-sheets values "none,none"... I [25/May/2024:07:49:55 +0000] [Job 5] Adding start banner page "none". D [25/May/2024:07:49:55 +0000] Discarding unused job-created event... D [25/May/2024:07:49:55 +0000] cupsdMarkDirty(---J-) D [25/May/2024:07:49:55 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [25/May/2024:07:49:55 +0000] [Job 5] Adding end banner page "none". I [25/May/2024:07:49:55 +0000] [Job 5] File of type application/vnd.cups-pdf-banner queued by "admin". D [25/May/2024:07:49:55 +0000] [Job 5] hold_until=0 I [25/May/2024:07:49:55 +0000] [Job 5] Queued on "Canon_7018C" by "admin". D [25/May/2024:07:49:55 +0000] [Job 5] time-at-processing=1716623395 D [25/May/2024:07:49:55 +0000] cupsdMarkDirty(---J-) D [25/May/2024:07:49:55 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [25/May/2024:07:49:55 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [25/May/2024:07:49:55 +0000] Discarding unused printer-state-changed event... D [25/May/2024:07:49:55 +0000] [Job 5] 4 filters for job: D [25/May/2024:07:49:55 +0000] [Job 5] bannertopdf (application/vnd.cups-pdf-banner to application/pdf, cost 32) D [25/May/2024:07:49:55 +0000] [Job 5] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66) D [25/May/2024:07:49:55 +0000] [Job 5] pdftops (application/vnd.cups-pdf to application/vnd.cups-postscript, cost 100) D [25/May/2024:07:49:55 +0000] [Job 5] pstocapt3 (application/vnd.cups-postscript to printer/Canon_7018C, cost 0) D [25/May/2024:07:49:55 +0000] [Job 5] job-sheets=none,none D [25/May/2024:07:49:55 +0000] [Job 5] argv[0]="Canon_7018C" D [25/May/2024:07:49:55 +0000] [Job 5] argv[1]="5" D [25/May/2024:07:49:55 +0000] [Job 5] argv[2]="admin" D [25/May/2024:07:49:55 +0000] [Job 5] argv[3]="Test Page" D [25/May/2024:07:49:55 +0000] [Job 5] argv[4]="1" D [25/May/2024:07:49:55 +0000] [Job 5] argv[5]="job-uuid=urn:uuid:73c27337-3b1e-3d7b-4518-8ce8cbb250d4 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1716623395 time-at-processing=1716623395" D [25/May/2024:07:49:55 +0000] [Job 5] argv[6]="/var/spool/cups/d00005-001" D [25/May/2024:07:49:55 +0000] [Job 5] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [25/May/2024:07:49:55 +0000] [Job 5] envp[1]="CUPS_DATADIR=/usr/share/cups" D [25/May/2024:07:49:55 +0000] [Job 5] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root" D [25/May/2024:07:49:55 +0000] [Job 5] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [25/May/2024:07:49:55 +0000] [Job 5] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [25/May/2024:07:49:55 +0000] [Job 5] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [25/May/2024:07:49:55 +0000] [Job 5] envp[6]="CUPS_SERVERROOT=/etc/cups" D [25/May/2024:07:49:55 +0000] [Job 5] envp[7]="CUPS_STATEDIR=/run/cups" D [25/May/2024:07:49:55 +0000] [Job 5] envp[8]="HOME=/var/spool/cups/tmp" D [25/May/2024:07:49:55 +0000] [Job 5] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [25/May/2024:07:49:55 +0000] [Job 5] envp[10]="SERVER_ADMIN=root@f1d8321aea9d" D [25/May/2024:07:49:55 +0000] [Job 5] envp[11]="SOFTWARE=CUPS/2.2.10" D [25/May/2024:07:49:55 +0000] [Job 5] envp[12]="TMPDIR=/var/spool/cups/tmp" D [25/May/2024:07:49:55 +0000] [Job 5] envp[13]="USER=root" D [25/May/2024:07:49:55 +0000] [Job 5] envp[14]="CUPS_MAX_MESSAGE=2047" D [25/May/2024:07:49:55 +0000] [Job 5] envp[15]="CUPS_SERVER=/run/cups/cups.sock" D [25/May/2024:07:49:55 +0000] [Job 5] envp[16]="CUPS_ENCRYPTION=IfRequested" D [25/May/2024:07:49:55 +0000] [Job 5] envp[17]="IPP_PORT=631" D [25/May/2024:07:49:55 +0000] [Job 5] envp[18]="CHARSET=utf-8" D [25/May/2024:07:49:55 +0000] [Job 5] envp[19]="LANG=en_US.UTF-8" D [25/May/2024:07:49:55 +0000] [Job 5] envp[20]="PPD=/etc/cups/ppd/Canon_7018C.ppd" D [25/May/2024:07:49:55 +0000] [Job 5] envp[21]="RIP_MAX_CACHE=128m" D [25/May/2024:07:49:55 +0000] [Job 5] envp[22]="CONTENT_TYPE=application/vnd.cups-pdf-banner" D [25/May/2024:07:49:55 +0000] [Job 5] envp[23]="DEVICE_URI=usb://Canon/LBP7010C/7018C?serial=0000A1D5DFCw" D [25/May/2024:07:49:55 +0000] [Job 5] envp[24]="PRINTER_INFO=Canon LBP7010C/7018C" D [25/May/2024:07:49:55 +0000] [Job 5] envp[25]="PRINTER_LOCATION=" D [25/May/2024:07:49:55 +0000] [Job 5] envp[26]="PRINTER=Canon_7018C" D [25/May/2024:07:49:55 +0000] [Job 5] envp[27]="PRINTER_STATE_REASONS=none" D [25/May/2024:07:49:55 +0000] [Job 5] envp[28]="CUPS_FILETYPE=document" D [25/May/2024:07:49:55 +0000] [Job 5] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript" D [25/May/2024:07:49:55 +0000] [Job 5] envp[30]="AUTH_I****" I [25/May/2024:07:49:55 +0000] [Job 5] Started filter /usr/lib/cups/filter/bannertopdf (PID 54) I [25/May/2024:07:49:55 +0000] [Job 5] Started filter /usr/lib/cups/filter/pdftopdf (PID 55) I [25/May/2024:07:49:55 +0000] [Job 5] Started filter /usr/lib/cups/filter/pdftops (PID 56) I [25/May/2024:07:49:55 +0000] [Job 5] Started filter /usr/lib/cups/filter/pstocapt3 (PID 57) I [25/May/2024:07:49:55 +0000] [Job 5] Started backend /usr/lib/cups/backend/usb (PID 58) D [25/May/2024:07:49:55 +0000] Discarding unused job-state-changed event... D [25/May/2024:07:49:55 +0000] [Client 26] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/Canon_7018C) from localhost. D [25/May/2024:07:49:55 +0000] [Client 26] Content-Length: 191 D [25/May/2024:07:49:55 +0000] [Client 26] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [25/May/2024:07:49:55 +0000] [Client 26] con->http=0x5595d0ef90 D [25/May/2024:07:49:55 +0000] [Client 26] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=191, response=0x5595ccf010(IPP_STATE_IDLE), pipe_pid=0, file=-1 D [25/May/2024:07:49:55 +0000] [Client 26] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [25/May/2024:07:49:55 +0000] [Client 26] bytes=0, http_state=0, data_remaining=191 D [25/May/2024:07:49:55 +0000] [Client 26] Flushing write buffer. D [25/May/2024:07:49:55 +0000] [Client 26] New state is HTTP_STATE_WAITING D [25/May/2024:07:49:55 +0000] [Client 26] Waiting for request. D [25/May/2024:07:49:55 +0000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.3.193\" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"admin\" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.10\" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.3.193\" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"admin\" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.10\" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetArray: job_uri[0]=\"/jobs/5\" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetArray: job_id[0]=\"5\" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetArray: job_state[0]=\"3\" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetArray: job_state_message[0]=\"\" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetArray: job_state_reasons[0]=\"none\" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetVariable: refresh_page=\"2;URL=/printers/Canon_7018C\" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetVariable: TITLE=\"Print Test Page\" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.3.193\" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"admin\" D [25/May/2024:07:49:55 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.10\" D [25/May/2024:07:49:55 +0000] [Client 1] CGI data ready to be sent. D [25/May/2024:07:49:55 +0000] [CGI] cgiSetVariable: PRINTER_NAME=\"Canon_7018C\" D [25/May/2024:07:49:55 +0000] [Client 26] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [25/May/2024:07:49:55 +0000] [Client 26] Closing connection. D [25/May/2024:07:49:55 +0000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [25/May/2024:07:49:55 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:49:55 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=53, file=13 D [25/May/2024:07:49:55 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:49:55 +0000] [Client 1] Script header: Content-Type: text/html;charset=utf-8 D [25/May/2024:07:49:55 +0000] [Client 1] Script header: D [25/May/2024:07:49:55 +0000] [Client 1] Sending status 200 for CGI. D [25/May/2024:07:49:55 +0000] [Client 1] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [25/May/2024:07:49:55 +0000] PID 53 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [25/May/2024:07:49:55 +0000] [Job 5] PID 57 (/usr/lib/cups/filter/pstocapt3) stopped with status 108 (Exec format error) D [25/May/2024:07:49:55 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:49:55 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=53, file=13 D [25/May/2024:07:49:55 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:49:55 +0000] [Client 1] CGI data ready to be sent. D [25/May/2024:07:49:55 +0000] [Job 5] execv failed: Exec format error D [25/May/2024:07:49:55 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:49:55 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=53, file=13 D [25/May/2024:07:49:55 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:49:55 +0000] [Client 1] Sending 0-length chunk. D [25/May/2024:07:49:55 +0000] [Client 1] Flushing write buffer. D [25/May/2024:07:49:55 +0000] [Client 1] New state is HTTP_STATE_WAITING D [25/May/2024:07:49:55 +0000] [Client 1] Waiting for request. D [25/May/2024:07:49:55 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [25/May/2024:07:49:55 +0000] [Job 5] pdftops - copying to temp print file \"/var/spool/cups/tmp/00038665db725\" D [25/May/2024:07:49:55 +0000] [Job 5] pdftopdf: Last filter determined by the PPD: pstocapt3; FINAL_CONTENT_TYPE: application/vnd.cups-postscript => pdftopdf will not log pages in page_log. D [25/May/2024:07:49:55 +0000] [Job 5] PDF template file doesn\'t have form. It\'s okay. D [25/May/2024:07:49:55 +0000] [Job 5] Loading USB quirks from \"/usr/share/cups/usb\". D [25/May/2024:07:49:55 +0000] [Job 5] Loaded 172 quirks. D [25/May/2024:07:49:55 +0000] [Job 5] Printing on printer with URI: usb://Canon/LBP7010C/7018C?serial=0000A1D5DFCw D [25/May/2024:07:49:55 +0000] [Job 5] libusb_get_device_list=3 D [25/May/2024:07:49:55 +0000] [Job 5] STATE: +connecting-to-device D [25/May/2024:07:49:55 +0000] cupsdMarkDirty(---J-) D [25/May/2024:07:49:55 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files" D [25/May/2024:07:49:55 +0000] Discarding unused printer-state-changed event... D [25/May/2024:07:49:55 +0000] [Job 5] PID 54 (/usr/lib/cups/filter/bannertopdf) exited with no errors. D [25/May/2024:07:49:55 +0000] [Job 5] STATE: -connecting-to-device D [25/May/2024:07:49:55 +0000] cupsdMarkDirty(---J-) D [25/May/2024:07:49:55 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files" D [25/May/2024:07:49:55 +0000] Discarding unused printer-state-changed event... D [25/May/2024:07:49:55 +0000] [Job 5] Device protocol: 2 D [25/May/2024:07:49:55 +0000] [Job 5] Sending data to printer. D [25/May/2024:07:49:55 +0000] Discarding unused job-progress event... D [25/May/2024:07:49:55 +0000] Discarding unused printer-state-changed event... D [25/May/2024:07:49:55 +0000] [Job 5] Sent 0 bytes... D [25/May/2024:07:49:55 +0000] [Job 5] PID 55 (/usr/lib/cups/filter/pdftopdf) exited with no errors. D [25/May/2024:07:49:55 +0000] [Job 5] Printer make and model: Canon lbp7010c/7018c D [25/May/2024:07:49:55 +0000] [Job 5] Running command line for pstops: pstops 5 admin \'Test Page\' 1 \'job-uuid=urn:uuid:73c27337-3b1e-3d7b-4518-8ce8cbb250d4 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1716623395 time-at-processing=1716623395\' D [25/May/2024:07:49:55 +0000] [Job 5] Using image rendering resolution 600 dpi D [25/May/2024:07:49:55 +0000] [Job 5] Running command line for gs: gs -q -dNOPAUSE -dBATCH -dSAFER -dNOMEDIAATTRS -sDEVICE=ps2write -dShowAcroForm -sOUTPUTFILE=%stdout -dLanguageLevel=3 -r600 -dCompressFonts=false -dNoT3CCITT -dNOINTERPOLATE -c \'save pop\' -f /var/spool/cups/tmp/00038665db725 D [25/May/2024:07:49:55 +0000] [Job 5] Started filter gs (PID 62) D [25/May/2024:07:49:55 +0000] [Job 5] Started filter pstops (PID 63) D [25/May/2024:07:49:56 +0000] [Job 5] Page = 595x842; 14,14 to 581,828 D [25/May/2024:07:49:56 +0000] [Job 5] slow_collate=0, slow_duplex=0, slow_order=0 D [25/May/2024:07:49:56 +0000] [Job 5] Before copy_comments - %!PS-Adobe-3.0 D [25/May/2024:07:49:56 +0000] [Job 5] %!PS-Adobe-3.0 D [25/May/2024:07:49:56 +0000] [Job 5] %%BoundingBox: 0 0 595 842 D [25/May/2024:07:49:56 +0000] [Job 5] %%HiResBoundingBox: 0 0 595.00 842.00 D [25/May/2024:07:49:56 +0000] [Job 5] %%Creator: GPL Ghostscript 927 (ps2write) D [25/May/2024:07:49:56 +0000] [Job 5] %%LanguageLevel: 2 D [25/May/2024:07:49:56 +0000] [Job 5] %%CreationDate: D:20240525074956Z00\'00\' D [25/May/2024:07:49:56 +0000] [Job 5] %%Pages: 1 D [25/May/2024:07:49:56 +0000] [Job 5] %%EndComments D [25/May/2024:07:49:56 +0000] [Job 5] Before copy_prolog - %%BeginProlog D [25/May/2024:07:49:56 +0000] [Job 5] Before copy_setup - %%Page: 1 1 D [25/May/2024:07:49:56 +0000] [Job 5] Before page loop - %%Page: 1 1 D [25/May/2024:07:49:56 +0000] [Job 5] Copying page 1... D [25/May/2024:07:49:56 +0000] [Job 5] pagew = 566.7, pagel = 813.7 D [25/May/2024:07:49:56 +0000] [Job 5] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842 D [25/May/2024:07:49:56 +0000] [Job 5] PageLeft = 14.2, PageRight = 580.8 D [25/May/2024:07:49:56 +0000] [Job 5] PageTop = 827.8, PageBottom = 14.2 D [25/May/2024:07:49:56 +0000] [Job 5] PageWidth = 595.0, PageLength = 842.0 D [25/May/2024:07:49:56 +0000] [Job 5] Wrote 1 pages... D [25/May/2024:07:49:56 +0000] [Job 5] PID 63 (pstops) exited with no errors. D [25/May/2024:07:49:56 +0000] [Job 5] PID 62 (gs) exited with no errors. D [25/May/2024:07:49:56 +0000] [Job 5] PID 56 (/usr/lib/cups/filter/pdftops) exited with no errors. D [25/May/2024:07:49:56 +0000] [Job 5] Waiting for read thread to exit... D [25/May/2024:07:49:57 +0000] [Client 1] GET /printers/Canon_7018C HTTP/1.1 D [25/May/2024:07:49:57 +0000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [25/May/2024:07:49:57 +0000] [Client 1] Read: status=200, state=3 D [25/May/2024:07:49:57 +0000] [Client 1] Authorized as "admin" using Basic. D [25/May/2024:07:49:57 +0000] [Client 1] Processing GET /printers/Canon_7018C D [25/May/2024:07:49:57 +0000] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [25/May/2024:07:49:57 +0000] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [25/May/2024:07:49:57 +0000] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [25/May/2024:07:49:57 +0000] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root" D [25/May/2024:07:49:57 +0000] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [25/May/2024:07:49:57 +0000] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [25/May/2024:07:49:57 +0000] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [25/May/2024:07:49:57 +0000] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [25/May/2024:07:49:57 +0000] [CGI] envp[7] = "CUPS_STATEDIR=/run/cups" D [25/May/2024:07:49:57 +0000] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [25/May/2024:07:49:57 +0000] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [25/May/2024:07:49:57 +0000] [CGI] envp[10] = "SERVER_ADMIN=root@f1d8321aea9d" D [25/May/2024:07:49:57 +0000] [CGI] envp[11] = "SOFTWARE=CUPS/2.2.10" D [25/May/2024:07:49:57 +0000] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [25/May/2024:07:49:57 +0000] [CGI] envp[13] = "USER=root" D [25/May/2024:07:49:57 +0000] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047" D [25/May/2024:07:49:57 +0000] [CGI] envp[15] = "CUPS_SERVER=/run/cups/cups.sock" D [25/May/2024:07:49:57 +0000] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested" D [25/May/2024:07:49:57 +0000] [CGI] envp[17] = "IPP_PORT=631" D [25/May/2024:07:49:57 +0000] [CGI] envp[18] = "AUTH_TYPE=Basic" D [25/May/2024:07:49:57 +0000] [CGI] envp[19] = "LANG=en_US.UTF8" D [25/May/2024:07:49:57 +0000] [CGI] envp[20] = "REDIRECT_STATUS=1" D [25/May/2024:07:49:57 +0000] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1" D [25/May/2024:07:49:57 +0000] [CGI] envp[22] = "SERVER_NAME=192.168.3.193" D [25/May/2024:07:49:57 +0000] [CGI] envp[23] = "SERVER_PORT=631" D [25/May/2024:07:49:57 +0000] [CGI] envp[24] = "REMOTE_ADDR=192.168.3.189" D [25/May/2024:07:49:57 +0000] [CGI] envp[25] = "REMOTE_HOST=192.168.3.189" D [25/May/2024:07:49:57 +0000] [CGI] envp[26] = "SCRIPT_NAME=/printers/Canon_7018C" D [25/May/2024:07:49:57 +0000] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/printers/Canon_7018C" D [25/May/2024:07:49:57 +0000] [CGI] envp[28] = "PATH_INFO=/Canon_7018C" D [25/May/2024:07:49:57 +0000] [CGI] envp[29] = "REMOTE_USER=admin" D [25/May/2024:07:49:57 +0000] [CGI] envp[30] = "SERVER_PROTOCOL=HTTP/1.1" D [25/May/2024:07:49:57 +0000] [CGI] envp[31] = "HTTP_COOKIE=org.cups.sid=aa3aec99084389badfd1f2ce0e4c69ef" D [25/May/2024:07:49:57 +0000] [CGI] envp[32] = "HTTP_USER_AGENT=Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/125.0.0.0 Safari/537.36" D [25/May/2024:07:49:57 +0000] [CGI] envp[33] = "HTTP_REFERER=http://192.168.3.193:631/printers/Canon_7018C" D [25/May/2024:07:49:57 +0000] [CGI] envp[34] = "REQUEST_METHOD=GET" D [25/May/2024:07:49:57 +0000] [CGI] envp[35] = "QUERY_STRING=" D [25/May/2024:07:49:57 +0000] cupsdAddCert: Adding certificate for PID 64 D [25/May/2024:07:49:57 +0000] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 64) I [25/May/2024:07:49:57 +0000] [Client 1] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=64, file=12) D [25/May/2024:07:49:57 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:49:57 +0000] [CGI] org.cups.sid cookie is \"aa3aec99084389badfd1f2ce0e4c69ef\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: SECTION=\"printers\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: PRINTER_NAME=\"Canon_7018C\" D [25/May/2024:07:49:57 +0000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [25/May/2024:07:49:57 +0000] [Client 27] Server address is "/run/cups/cups.sock". D [25/May/2024:07:49:57 +0000] [Client 27] Accepted from localhost (Domain) D [25/May/2024:07:49:57 +0000] [Client 27] Waiting for request. D [25/May/2024:07:49:57 +0000] [Client 27] POST / HTTP/1.1 D [25/May/2024:07:49:57 +0000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [25/May/2024:07:49:57 +0000] [Client 27] Read: status=200, state=6 D [25/May/2024:07:49:57 +0000] [Client 27] No authentication data provided. D [25/May/2024:07:49:57 +0000] [Client 27] 2.0 CUPS-Get-Default 1 D [25/May/2024:07:49:57 +0000] CUPS-Get-Default D [25/May/2024:07:49:57 +0000] CUPS-Get-Default client-error-not-found: No default printer. D [25/May/2024:07:49:57 +0000] [Client 27] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost. D [25/May/2024:07:49:57 +0000] [Client 27] Content-Length: 113 D [25/May/2024:07:49:57 +0000] [Client 27] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [25/May/2024:07:49:57 +0000] [Client 27] con->http=0x5595d0ef90 D [25/May/2024:07:49:57 +0000] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x5595cf9b90(IPP_STATE_DATA), pipe_pid=0, file=-1 D [25/May/2024:07:49:57 +0000] [Client 27] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [25/May/2024:07:49:57 +0000] [Client 27] bytes=0, http_state=0, data_remaining=113 D [25/May/2024:07:49:57 +0000] [Client 27] Flushing write buffer. D [25/May/2024:07:49:57 +0000] [Client 27] New state is HTTP_STATE_WAITING D [25/May/2024:07:49:57 +0000] [Client 27] Waiting for request. D [25/May/2024:07:49:57 +0000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [25/May/2024:07:49:57 +0000] [Client 27] POST / HTTP/1.1 D [25/May/2024:07:49:57 +0000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [25/May/2024:07:49:57 +0000] [Client 27] Read: status=200, state=6 D [25/May/2024:07:49:57 +0000] [Client 27] No authentication data provided. D [25/May/2024:07:49:57 +0000] [Client 27] 2.0 Get-Printer-Attributes 2 D [25/May/2024:07:49:57 +0000] Get-Printer-Attributes ipp://localhost/printers/Canon_7018C D [25/May/2024:07:49:57 +0000] [Client 27] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Canon_7018C) from localhost. D [25/May/2024:07:49:57 +0000] [Client 27] Content-Length: 663 D [25/May/2024:07:49:57 +0000] [Client 27] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [25/May/2024:07:49:57 +0000] [CGI] show_printer(http=0x55b204b9b0, printer=\"Canon_7018C\") D [25/May/2024:07:49:57 +0000] [Client 27] con->http=0x5595d0ef90 D [25/May/2024:07:49:57 +0000] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=663, response=0x5595d03a60(IPP_STATE_DATA), pipe_pid=0, file=-1 D [25/May/2024:07:49:57 +0000] [Client 27] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [25/May/2024:07:49:57 +0000] [Client 27] bytes=0, http_state=0, data_remaining=663 D [25/May/2024:07:49:57 +0000] [Client 27] Flushing write buffer. D [25/May/2024:07:49:57 +0000] [Client 27] New state is HTTP_STATE_WAITING D [25/May/2024:07:49:57 +0000] [Client 27] Waiting for request. D [25/May/2024:07:49:57 +0000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.3.193\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"admin\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.10\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.3.193\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"admin\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.10\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: printer_is_shared[0]=\"1\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: printer_state[0]=\"4\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/Canon_7018C\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: printer_name[0]=\"Canon_7018C\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: printer_location[0]=\"\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: printer_info[0]=\"Canon LBP7010C/7018C\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: device_uri[0]=\"usb://Canon/LBP7010C/7018C?serial=0000A1D5DFCw\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: color_supported[0]=\"1\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: printer_make_and_model[0]=\"Canon LBP7010C/7018C CAPT (UK)\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: media_default[0]=\"iso_a4_210x297mm\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: sides_supported[0]=\"one-sided\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: sides_default[0]=\"one-sided\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: printer_commands[0]=\"AutoConfigure, Clean, PrintSelfTestPage\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"1\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: refresh_page=\"10;URL=/printers/Canon_7018C\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: TITLE=\"Canon_7018C\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.3.193\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"admin\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.10\" D [25/May/2024:07:49:57 +0000] [Client 1] CGI data ready to be sent. D [25/May/2024:07:49:57 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:49:57 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=64, file=12 D [25/May/2024:07:49:57 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:49:57 +0000] [Client 1] Script header: Content-Type: text/html;charset=utf-8 D [25/May/2024:07:49:57 +0000] [Client 1] Script header: D [25/May/2024:07:49:57 +0000] [Client 1] Sending status 200 for CGI. D [25/May/2024:07:49:57 +0000] [Client 1] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [25/May/2024:07:49:57 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:49:57 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=64, file=12 D [25/May/2024:07:49:57 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:49:57 +0000] [Client 1] CGI data ready to be sent. D [25/May/2024:07:49:57 +0000] [CGI] Regular expression \".Clean.\" D [25/May/2024:07:49:57 +0000] [CGI] matches[0].rm_so=0 D [25/May/2024:07:49:57 +0000] [CGI] matches[1].rm_so=-1 D [25/May/2024:07:49:57 +0000] [CGI] Regular expression \".PrintSelfTestPage.\" D [25/May/2024:07:49:57 +0000] [CGI] matches[0].rm_so=0 D [25/May/2024:07:49:57 +0000] [CGI] matches[1].rm_so=-1 D [25/May/2024:07:49:57 +0000] [Client 27] POST / HTTP/1.1 D [25/May/2024:07:49:57 +0000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [25/May/2024:07:49:57 +0000] [Client 27] Read: status=200, state=6 D [25/May/2024:07:49:57 +0000] [Client 27] No authentication data provided. D [25/May/2024:07:49:57 +0000] [Client 27] 2.0 Get-Jobs 3 D [25/May/2024:07:49:57 +0000] Get-Jobs ipp://localhost:631/printers/Canon_7018C D [25/May/2024:07:49:57 +0000] [Client 27] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/Canon_7018C) from localhost. D [25/May/2024:07:49:57 +0000] [Client 27] Content-Length: 337 D [25/May/2024:07:49:57 +0000] [Client 27] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [25/May/2024:07:49:57 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:49:57 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=64, file=12 D [25/May/2024:07:49:57 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:49:57 +0000] [Client 27] con->http=0x5595d0ef90 D [25/May/2024:07:49:57 +0000] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=337, response=0x5595cf9f80(IPP_STATE_DATA), pipe_pid=0, file=-1 D [25/May/2024:07:49:57 +0000] [Client 27] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [25/May/2024:07:49:57 +0000] [Client 27] bytes=0, http_state=0, data_remaining=337 D [25/May/2024:07:49:57 +0000] [Client 27] Flushing write buffer. D [25/May/2024:07:49:57 +0000] [Client 27] New state is HTTP_STATE_WAITING D [25/May/2024:07:49:57 +0000] [Client 27] Waiting for request. D [25/May/2024:07:49:57 +0000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [25/May/2024:07:49:57 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:49:57 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=64, file=12 D [25/May/2024:07:49:57 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:49:57 +0000] [CGI] cgiClearVariables called. D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: SECTION=\"printers\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: TOTAL=\"1\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.3.193\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"admin\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.10\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: job_printer_name[0]=\"Canon_7018C\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: job_printer_uri[0]=\"/printers/Canon_7018C\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: time_at_completed[0]=\"novalue\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: time_at_creation[0]=\"Sat May 25 07:49:55 2024\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: time_at_processing[0]=\"Sat May 25 07:49:55 2024\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: job_id[0]=\"5\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: job_state[0]=\"5\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: job_impressions_completed[0]=\"0\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: job_k_octets[0]=\"1\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetArray: job_printer_state_message[0]=\"Filter failed\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: PRINTER_NAME=\"Canon_7018C\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/Canon_7018C\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: THISURL=\"/printers/Canon_7018C\" D [25/May/2024:07:49:57 +0000] [CGI] cgiSetVariable: SEARCH_DEST=\"Canon_7018C\" D [25/May/2024:07:49:57 +0000] [Client 1] CGI data ready to be sent. D [25/May/2024:07:49:57 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:49:57 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=64, file=12 D [25/May/2024:07:49:57 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:49:57 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:49:57 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=64, file=12 D [25/May/2024:07:49:57 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:49:57 +0000] [Client 1] CGI data ready to be sent. D [25/May/2024:07:49:57 +0000] [Client 27] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [25/May/2024:07:49:57 +0000] [Client 27] Closing connection. D [25/May/2024:07:49:57 +0000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [25/May/2024:07:49:57 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:49:57 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=64, file=12 D [25/May/2024:07:49:57 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:49:57 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:49:57 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=64, file=12 D [25/May/2024:07:49:57 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:49:57 +0000] PID 64 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [25/May/2024:07:49:57 +0000] [Client 1] CGI data ready to be sent. D [25/May/2024:07:49:57 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:49:57 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=64, file=12 D [25/May/2024:07:49:57 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:49:57 +0000] [Client 1] Sending 0-length chunk. D [25/May/2024:07:49:57 +0000] [Client 1] Flushing write buffer. D [25/May/2024:07:49:57 +0000] [Client 1] New state is HTTP_STATE_WAITING D [25/May/2024:07:49:57 +0000] [Client 1] Waiting for request. D [25/May/2024:07:49:57 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [25/May/2024:07:50:03 +0000] [Job 5] Read thread still active, aborting the pending read... D [25/May/2024:07:50:04 +0000] [Job 5] PID 58 (/usr/lib/cups/backend/usb) exited with no errors. D [25/May/2024:07:50:04 +0000] Discarding unused job-state-changed event... E [25/May/2024:07:50:04 +0000] [Job 5] Job stopped due to filter errors; please consult the /var/log/cups/error_log file for details. D [25/May/2024:07:50:04 +0000] cupsdMarkDirty(---J-) D [25/May/2024:07:50:04 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [25/May/2024:07:50:04 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [25/May/2024:07:50:04 +0000] Discarding unused printer-state-changed event... D [25/May/2024:07:50:04 +0000] [Job 5] The following messages were recorded from 07:49:55 to 07:49:55 D [25/May/2024:07:50:04 +0000] [Job 5] Printer found with device ID: MFG:Canon;MDL:LBP7010C/7018C;CMD:CAPT;VER:3.0;CLS:PRINTER;DES:Canon LBP7010C/7018C Device URI: usb://Canon/LBP7010C/7018C?serial=0000A1D5DFCw D [25/May/2024:07:50:04 +0000] [Job 5] End of messages D [25/May/2024:07:50:04 +0000] [Job 5] printer-state=3(idle) D [25/May/2024:07:50:04 +0000] [Job 5] printer-state-message="Sending data to printer." D [25/May/2024:07:50:04 +0000] [Job 5] printer-state-reasons=none D [25/May/2024:07:50:05 +0000] [Job 5] Unloading... D [25/May/2024:07:50:07 +0000] [Client 1] GET /printers/Canon_7018C HTTP/1.1 D [25/May/2024:07:50:07 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files" D [25/May/2024:07:50:07 +0000] [Client 1] Read: status=200, state=3 D [25/May/2024:07:50:07 +0000] [Client 1] Authorized as "admin" using Basic. D [25/May/2024:07:50:07 +0000] [Client 1] Processing GET /printers/Canon_7018C D [25/May/2024:07:50:07 +0000] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [25/May/2024:07:50:07 +0000] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [25/May/2024:07:50:07 +0000] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [25/May/2024:07:50:07 +0000] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root" D [25/May/2024:07:50:07 +0000] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [25/May/2024:07:50:07 +0000] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [25/May/2024:07:50:07 +0000] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [25/May/2024:07:50:07 +0000] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [25/May/2024:07:50:07 +0000] [CGI] envp[7] = "CUPS_STATEDIR=/run/cups" D [25/May/2024:07:50:07 +0000] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [25/May/2024:07:50:07 +0000] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [25/May/2024:07:50:07 +0000] [CGI] envp[10] = "SERVER_ADMIN=root@f1d8321aea9d" D [25/May/2024:07:50:07 +0000] [CGI] envp[11] = "SOFTWARE=CUPS/2.2.10" D [25/May/2024:07:50:07 +0000] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [25/May/2024:07:50:07 +0000] [CGI] envp[13] = "USER=root" D [25/May/2024:07:50:07 +0000] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047" D [25/May/2024:07:50:07 +0000] [CGI] envp[15] = "CUPS_SERVER=/run/cups/cups.sock" D [25/May/2024:07:50:07 +0000] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested" D [25/May/2024:07:50:07 +0000] [CGI] envp[17] = "IPP_PORT=631" D [25/May/2024:07:50:07 +0000] [CGI] envp[18] = "AUTH_TYPE=Basic" D [25/May/2024:07:50:07 +0000] [CGI] envp[19] = "LANG=en_US.UTF8" D [25/May/2024:07:50:07 +0000] [CGI] envp[20] = "REDIRECT_STATUS=1" D [25/May/2024:07:50:07 +0000] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1" D [25/May/2024:07:50:07 +0000] [CGI] envp[22] = "SERVER_NAME=192.168.3.193" D [25/May/2024:07:50:07 +0000] [CGI] envp[23] = "SERVER_PORT=631" D [25/May/2024:07:50:07 +0000] [CGI] envp[24] = "REMOTE_ADDR=192.168.3.189" D [25/May/2024:07:50:07 +0000] [CGI] envp[25] = "REMOTE_HOST=192.168.3.189" D [25/May/2024:07:50:07 +0000] [CGI] envp[26] = "SCRIPT_NAME=/printers/Canon_7018C" D [25/May/2024:07:50:07 +0000] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/printers/Canon_7018C" D [25/May/2024:07:50:07 +0000] [CGI] envp[28] = "PATH_INFO=/Canon_7018C" D [25/May/2024:07:50:07 +0000] [CGI] envp[29] = "REMOTE_USER=admin" D [25/May/2024:07:50:07 +0000] [CGI] envp[30] = "SERVER_PROTOCOL=HTTP/1.1" D [25/May/2024:07:50:07 +0000] [CGI] envp[31] = "HTTP_COOKIE=org.cups.sid=aa3aec99084389badfd1f2ce0e4c69ef" D [25/May/2024:07:50:07 +0000] [CGI] envp[32] = "HTTP_USER_AGENT=Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/125.0.0.0 Safari/537.36" D [25/May/2024:07:50:07 +0000] [CGI] envp[33] = "HTTP_REFERER=http://192.168.3.193:631/printers/Canon_7018C" D [25/May/2024:07:50:07 +0000] [CGI] envp[34] = "REQUEST_METHOD=GET" D [25/May/2024:07:50:07 +0000] [CGI] envp[35] = "QUERY_STRING=" D [25/May/2024:07:50:07 +0000] cupsdAddCert: Adding certificate for PID 65 D [25/May/2024:07:50:07 +0000] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 65) I [25/May/2024:07:50:07 +0000] [Client 1] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=65, file=12) D [25/May/2024:07:50:07 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:50:07 +0000] [CGI] org.cups.sid cookie is \"aa3aec99084389badfd1f2ce0e4c69ef\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: SECTION=\"printers\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: PRINTER_NAME=\"Canon_7018C\" D [25/May/2024:07:50:07 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [25/May/2024:07:50:07 +0000] [Client 28] Server address is "/run/cups/cups.sock". D [25/May/2024:07:50:07 +0000] [Client 28] Accepted from localhost (Domain) D [25/May/2024:07:50:07 +0000] [Client 28] Waiting for request. D [25/May/2024:07:50:07 +0000] [Client 28] POST / HTTP/1.1 D [25/May/2024:07:50:07 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [25/May/2024:07:50:07 +0000] [Client 28] Read: status=200, state=6 D [25/May/2024:07:50:07 +0000] [Client 28] No authentication data provided. D [25/May/2024:07:50:07 +0000] [Client 28] 2.0 CUPS-Get-Default 1 D [25/May/2024:07:50:07 +0000] CUPS-Get-Default D [25/May/2024:07:50:07 +0000] CUPS-Get-Default client-error-not-found: No default printer. D [25/May/2024:07:50:07 +0000] [Client 28] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost. D [25/May/2024:07:50:07 +0000] [Client 28] Content-Length: 113 D [25/May/2024:07:50:07 +0000] [Client 28] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [25/May/2024:07:50:07 +0000] [Client 28] con->http=0x5595d0ef90 D [25/May/2024:07:50:07 +0000] [Client 28] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x5595cfcd00(IPP_STATE_DATA), pipe_pid=0, file=-1 D [25/May/2024:07:50:07 +0000] [Client 28] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [25/May/2024:07:50:07 +0000] [Client 28] bytes=0, http_state=0, data_remaining=113 D [25/May/2024:07:50:07 +0000] [Client 28] Flushing write buffer. D [25/May/2024:07:50:07 +0000] [Client 28] New state is HTTP_STATE_WAITING D [25/May/2024:07:50:07 +0000] [Client 28] Waiting for request. D [25/May/2024:07:50:07 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [25/May/2024:07:50:07 +0000] [CGI] show_printer(http=0x55938419b0, printer=\"Canon_7018C\") D [25/May/2024:07:50:07 +0000] [Client 28] POST / HTTP/1.1 D [25/May/2024:07:50:07 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [25/May/2024:07:50:07 +0000] [Client 28] Read: status=200, state=6 D [25/May/2024:07:50:07 +0000] [Client 28] No authentication data provided. D [25/May/2024:07:50:07 +0000] [Client 28] 2.0 Get-Printer-Attributes 2 D [25/May/2024:07:50:07 +0000] Get-Printer-Attributes ipp://localhost/printers/Canon_7018C D [25/May/2024:07:50:07 +0000] [Client 28] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Canon_7018C) from localhost. D [25/May/2024:07:50:07 +0000] [Client 28] Content-Length: 663 D [25/May/2024:07:50:07 +0000] [Client 28] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [25/May/2024:07:50:07 +0000] [Client 28] con->http=0x5595d0ef90 D [25/May/2024:07:50:07 +0000] [Client 28] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=663, response=0x5595cf9b90(IPP_STATE_DATA), pipe_pid=0, file=-1 D [25/May/2024:07:50:07 +0000] [Client 28] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [25/May/2024:07:50:07 +0000] [Client 28] bytes=0, http_state=0, data_remaining=663 D [25/May/2024:07:50:07 +0000] [Client 28] Flushing write buffer. D [25/May/2024:07:50:07 +0000] [Client 28] New state is HTTP_STATE_WAITING D [25/May/2024:07:50:07 +0000] [Client 28] Waiting for request. D [25/May/2024:07:50:07 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.3.193\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"admin\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.10\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.3.193\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"admin\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.10\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: printer_is_shared[0]=\"1\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: printer_state[0]=\"3\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/Canon_7018C\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: printer_name[0]=\"Canon_7018C\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: printer_location[0]=\"\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: printer_info[0]=\"Canon LBP7010C/7018C\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: device_uri[0]=\"usb://Canon/LBP7010C/7018C?serial=0000A1D5DFCw\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: color_supported[0]=\"1\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: printer_make_and_model[0]=\"Canon LBP7010C/7018C CAPT (UK)\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: media_default[0]=\"iso_a4_210x297mm\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: sides_supported[0]=\"one-sided\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: sides_default[0]=\"one-sided\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: printer_commands[0]=\"AutoConfigure, Clean, PrintSelfTestPage\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"1\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: TITLE=\"Canon_7018C\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.3.193\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"admin\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.10\" D [25/May/2024:07:50:07 +0000] [Client 1] CGI data ready to be sent. D [25/May/2024:07:50:07 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:50:07 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=65, file=12 D [25/May/2024:07:50:07 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:50:07 +0000] [Client 1] Script header: Content-Type: text/html;charset=utf-8 D [25/May/2024:07:50:07 +0000] [Client 1] Script header: D [25/May/2024:07:50:07 +0000] [Client 1] Sending status 200 for CGI. D [25/May/2024:07:50:07 +0000] [Client 1] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [25/May/2024:07:50:07 +0000] [CGI] Regular expression \".Clean.\" D [25/May/2024:07:50:07 +0000] [CGI] matches[0].rm_so=0 D [25/May/2024:07:50:07 +0000] [CGI] matches[1].rm_so=-1 D [25/May/2024:07:50:07 +0000] [CGI] Regular expression \".PrintSelfTestPage.\" D [25/May/2024:07:50:07 +0000] [CGI] matches[0].rm_so=0 D [25/May/2024:07:50:07 +0000] [CGI] matches[1].rm_so=-1 D [25/May/2024:07:50:07 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:50:07 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=65, file=12 D [25/May/2024:07:50:07 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:50:07 +0000] [Client 1] CGI data ready to be sent. D [25/May/2024:07:50:07 +0000] [Client 28] POST / HTTP/1.1 D [25/May/2024:07:50:07 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [25/May/2024:07:50:07 +0000] [Client 28] Read: status=200, state=6 D [25/May/2024:07:50:07 +0000] [Client 28] No authentication data provided. D [25/May/2024:07:50:07 +0000] [Client 28] 2.0 Get-Jobs 3 D [25/May/2024:07:50:07 +0000] Get-Jobs ipp://localhost:631/printers/Canon_7018C D [25/May/2024:07:50:07 +0000] [Job 5] Loading attributes... D [25/May/2024:07:50:07 +0000] [Client 28] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/Canon_7018C) from localhost. D [25/May/2024:07:50:07 +0000] [Client 28] Content-Length: 356 D [25/May/2024:07:50:07 +0000] [Client 28] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [25/May/2024:07:50:07 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:50:07 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=65, file=12 D [25/May/2024:07:50:07 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:50:07 +0000] [Client 28] con->http=0x5595d0ef90 D [25/May/2024:07:50:07 +0000] [Client 28] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=356, response=0x5595cf9fb0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [25/May/2024:07:50:07 +0000] [Client 28] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [25/May/2024:07:50:07 +0000] [Client 28] bytes=0, http_state=0, data_remaining=356 D [25/May/2024:07:50:07 +0000] [Client 28] Flushing write buffer. D [25/May/2024:07:50:07 +0000] [Client 28] New state is HTTP_STATE_WAITING D [25/May/2024:07:50:07 +0000] [Client 28] Waiting for request. D [25/May/2024:07:50:07 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [25/May/2024:07:50:07 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:50:07 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=65, file=12 D [25/May/2024:07:50:07 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:50:07 +0000] [CGI] cgiClearVariables called. D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: SECTION=\"printers\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: TOTAL=\"1\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: SERVER_NAME=\"192.168.3.193\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: REMOTE_USER=\"admin\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.10\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: job_preserved[0]=\"1\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: job_printer_name[0]=\"Canon_7018C\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: job_printer_uri[0]=\"/printers/Canon_7018C\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: time_at_completed[0]=\"novalue\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: time_at_creation[0]=\"Sat May 25 07:49:55 2024\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: time_at_processing[0]=\"Sat May 25 07:49:55 2024\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: job_id[0]=\"5\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: job_state[0]=\"6\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: job_impressions_completed[0]=\"0\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: job_k_octets[0]=\"1\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetArray: job_printer_state_message[0]=\"Filter failed\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: PRINTER_NAME=\"Canon_7018C\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/Canon_7018C\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: THISURL=\"/printers/Canon_7018C\" D [25/May/2024:07:50:07 +0000] [CGI] cgiSetVariable: SEARCH_DEST=\"Canon_7018C\" D [25/May/2024:07:50:07 +0000] [Client 1] CGI data ready to be sent. D [25/May/2024:07:50:07 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:50:07 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=65, file=12 D [25/May/2024:07:50:07 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:50:07 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:50:07 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=65, file=12 D [25/May/2024:07:50:07 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:50:07 +0000] [Client 1] CGI data ready to be sent. D [25/May/2024:07:50:07 +0000] [Client 28] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [25/May/2024:07:50:07 +0000] [Client 28] Closing connection. D [25/May/2024:07:50:07 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [25/May/2024:07:50:07 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:50:07 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=65, file=12 D [25/May/2024:07:50:07 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:50:07 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:50:07 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=65, file=12 D [25/May/2024:07:50:07 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:50:07 +0000] [Client 1] CGI data ready to be sent. D [25/May/2024:07:50:07 +0000] PID 65 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [25/May/2024:07:50:07 +0000] [Client 1] con->http=0x5595ccae90 D [25/May/2024:07:50:07 +0000] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=65, file=12 D [25/May/2024:07:50:07 +0000] [Client 1] Waiting for CGI data. D [25/May/2024:07:50:07 +0000] [Client 1] Sending 0-length chunk. D [25/May/2024:07:50:07 +0000] [Client 1] Flushing write buffer. D [25/May/2024:07:50:07 +0000] [Client 1] New state is HTTP_STATE_WAITING D [25/May/2024:07:50:07 +0000] [Client 1] Waiting for request. D [25/May/2024:07:50:07 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" root@f1d8321aea9d:/var/log/cups#