cups2.4+gs9.56+cups-filter1.28+qpdf10.6.x => filter faild

[zhangyonglin911]

Hi, I have several brother printers. They have been working well remotely. Recently, I reinstalled the latest server system CentOS, and then recompiled and installed cups2 4，gs9. 56.1 qpdf10. 6. X cups filter. When I print "test page" through the web management page, it prompts: filter failed error.

I would appreciate it if you could help me see what the problem is.

Maybe you need some errs_ Log, I sorted it out and attached it.

[zhangyonglin911]

E [21/May/2022:08:20:14 +0800] Unknown directive IdleExitTimeout on line 34 of /etc/cups/cupsd.conf. W [21/May/2022:08:20:14 +0800] Printer drivers are deprecated and will stop working in a future version of CUPS. See https://github.com/OpenPrinting/cups-sharing/issues/4 W [21/May/2022:08:20:14 +0800] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files W [21/May/2022:08:20:14 +0800] CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files E [21/May/2022:08:20:30 +0800] [Job 3] Job stopped due to filter errors; please consult the /var/log/cups/error_log file for details. D [21/May/2022:08:20:30 +0800] [Job 3] The following messages were recorded from 08:20:26 AM to 08:20:30 AM D [21/May/2022:08:20:30 +0800] [Job 3] Applying default options... D [21/May/2022:08:20:30 +0800] [Job 3] Adding start banner page "none". D [21/May/2022:08:20:30 +0800] [Job 3] Adding end banner page "none". D [21/May/2022:08:20:30 +0800] [Job 3] File of type application/vnd.cups-pdf-banner queued by "anonymous". D [21/May/2022:08:20:30 +0800] [Job 3] hold_until=0 D [21/May/2022:08:20:30 +0800] [Job 3] Queued on "t20210525514lenovolj2400" by "anonymous". D [21/May/2022:08:20:30 +0800] [Job 3] time-at-processing=1653092426 D [21/May/2022:08:20:30 +0800] [Job 3] 3 filters for job: D [21/May/2022:08:20:30 +0800] [Job 3] bannertopdf (application/vnd.cups-pdf-banner to application/pdf, cost 32) D [21/May/2022:08:20:30 +0800] [Job 3] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66) D [21/May/2022:08:20:30 +0800] [Job 3] foomatic-rip (application/vnd.cups-pdf to printer/t20210525514lenovolj2400, cost 0) D [21/May/2022:08:20:30 +0800] [Job 3] job-sheets=none,none D [21/May/2022:08:20:30 +0800] [Job 3] argv[0]="t20210525514lenovolj2400" D [21/May/2022:08:20:30 +0800] [Job 3] argv[1]="3" D [21/May/2022:08:20:30 +0800] [Job 3] argv[2]="anonymous" D [21/May/2022:08:20:30 +0800] [Job 3] argv[3]="Test Page" D [21/May/2022:08:20:30 +0800] [Job 3] argv[4]="1" D [21/May/2022:08:20:30 +0800] [Job 3] argv[5]="job-uuid=urn:uuid:3a453ff0-70c3-32a2-5c0c-1004492676b8 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1653092426 time-at-processing=1653092426" D [21/May/2022:08:20:30 +0800] [Job 3] argv[6]="/var/spool/cups/d00003-001" D [21/May/2022:08:20:30 +0800] [Job 3] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [21/May/2022:08:20:30 +0800] [Job 3] envp[1]="CUPS_DATADIR=/usr/share/cups" D [21/May/2022:08:20:30 +0800] [Job 3] envp[2]="CUPS_DOCROOT=/usr/share/doc/cups" D [21/May/2022:08:20:30 +0800] [Job 3] envp[3]="CUPS_REQUESTROOT=/var/spool/cups" D [21/May/2022:08:20:30 +0800] [Job 3] envp[4]="CUPS_SERVERBIN=/usr/lib/cups" D [21/May/2022:08:20:30 +0800] [Job 3] envp[5]="CUPS_SERVERROOT=/etc/cups" D [21/May/2022:08:20:30 +0800] [Job 3] envp[6]="CUPS_STATEDIR=/var/run/cups" D [21/May/2022:08:20:30 +0800] [Job 3] envp[7]="HOME=/var/spool/cups/tmp" D [21/May/2022:08:20:30 +0800] [Job 3] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [21/May/2022:08:20:30 +0800] [Job 3] envp[9]="SERVER_ADMIN=root@VM-12-2-centos" D [21/May/2022:08:20:30 +0800] [Job 3] envp[10]="SOFTWARE=CUPS/2.4.1" D [21/May/2022:08:20:30 +0800] [Job 3] envp[11]="TMPDIR=/var/spool/cups/tmp" D [21/May/2022:08:20:30 +0800] [Job 3] envp[12]="USER=root" D [21/May/2022:08:20:30 +0800] [Job 3] envp[13]="CUPS_MAX_MESSAGE=2047" D [21/May/2022:08:20:30 +0800] [Job 3] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock" D [21/May/2022:08:20:30 +0800] [Job 3] envp[15]="CUPS_ENCRYPTION=IfRequested" D [21/May/2022:08:20:30 +0800] [Job 3] envp[16]="IPP_PORT=6310" D [21/May/2022:08:20:30 +0800] [Job 3] envp[17]="CHARSET=utf-8" D [21/May/2022:08:20:30 +0800] [Job 3] envp[18]="LANG=zh_CN.UTF-8" D [21/May/2022:08:20:30 +0800] [Job 3] envp[19]="PPD=/etc/cups/ppd/t20210525514lenovolj2400.ppd" D [21/May/2022:08:20:30 +0800] [Job 3] envp[20]="CONTENT_TYPE=application/vnd.cups-pdf-banner" D [21/May/2022:08:20:30 +0800] [Job 3] envp[21]="DEVICE_URI=ipp://t20210525514lenovolj2400.xxx.test.com:6318/xxx/xx" D [21/May/2022:08:20:30 +0800] [Job 3] envp[22]="PRINTER_INFO=t20210525514lenovolj2400" D [21/May/2022:08:20:30 +0800] [Job 3] envp[23]="PRINTER_LOCATION=" D [21/May/2022:08:20:30 +0800] [Job 3] envp[24]="PRINTER=t20210525514lenovolj2400" D [21/May/2022:08:20:30 +0800] [Job 3] envp[25]="PRINTER_STATE_REASONS=none" D [21/May/2022:08:20:30 +0800] [Job 3] envp[26]="CUPS_FILETYPE=document" D [21/May/2022:08:20:30 +0800] [Job 3] envp[27]="FINAL_CONTENT_TYPE=application/vnd.cups-pdf" D [21/May/2022:08:20:30 +0800] [Job 3] envp[28]="AUTH_I****" D [21/May/2022:08:20:30 +0800] [Job 3] Started filter /usr/lib/cups/filter/bannertopdf (PID 136426) D [21/May/2022:08:20:30 +0800] [Job 3] Started filter /usr/lib/cups/filter/pdftopdf (PID 136427) D [21/May/2022:08:20:30 +0800] [Job 3] Started filter /usr/lib/cups/filter/foomatic-rip (PID 136428) D [21/May/2022:08:20:30 +0800] [Job 3] Started backend /usr/lib/cups/backend/ipp (PID 136429) D [21/May/2022:08:20:30 +0800] [Job 3] PID 136426 (/usr/lib/cups/filter/bannertopdf) exited with no errors. D [21/May/2022:08:20:30 +0800] [Job 3] pdftopdf: Last filter determined by the PPD: foomatic-rip; FINAL_CONTENT_TYPE: application/vnd.cups-pdf => pdftopdf will log pages in page_log. D [21/May/2022:08:20:30 +0800] [Job 3] PDF template file doesn\'t have form. It\'s okay. D [21/May/2022:08:20:30 +0800] [Job 3] PDF interactive form and annotation flattening done via QPDF D [21/May/2022:08:20:30 +0800] [Job 3] pdftopdf: \"print-scaling\" IPP attribute: auto D [21/May/2022:08:20:30 +0800] [Job 3] pdftopdf: Print scaling mode: Do not scale, center, crop if needed D [21/May/2022:08:20:30 +0800] [Job 3] After Cropping: 595.000000 842.000000 595.000000 842.000000 D [21/May/2022:08:20:30 +0800] [Job 3] PAGE: 1 1 D [21/May/2022:08:20:30 +0800] [Job 3] Sending stdin for job... D [21/May/2022:08:20:30 +0800] [Job 3] STATE: +connecting-to-device D [21/May/2022:08:20:30 +0800] [Job 3] Looking up \"t20210525514lenovolj2400.xxx.test.com\"... D [21/May/2022:08:20:30 +0800] [Job 3] PID 136427 (/usr/lib/cups/filter/pdftopdf) exited with no errors. D [21/May/2022:08:20:30 +0800] [Job 3] Calling FindDeviceById(cups-t20210525514lenovolj2400) D [21/May/2022:08:20:30 +0800] [Job 3] Failed to send: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files D [21/May/2022:08:20:30 +0800] [Job 3] Failed to get find device cups-t20210525514lenovolj2400 D [21/May/2022:08:20:30 +0800] [Job 3] \'CM Color Calibration\' Mode in SPOOLER-LESS: Off D [21/May/2022:08:20:30 +0800] [Job 3] Getting input from file D [21/May/2022:08:20:30 +0800] [Job 3] foomatic-rip version 1.28.15 running... D [21/May/2022:08:20:30 +0800] [Job 3] Parsing PPD file ... D [21/May/2022:08:20:30 +0800] [Job 3] Added option ColorSpace D [21/May/2022:08:20:30 +0800] [Job 3] Added option PageSize D [21/May/2022:08:20:30 +0800] [Job 3] Added option ImageableArea D [21/May/2022:08:20:30 +0800] [Job 3] Added option PaperDimension D [21/May/2022:08:20:30 +0800] [Job 3] Added option EconoMode D [21/May/2022:08:20:30 +0800] [Job 3] Added option InputSlot D [21/May/2022:08:20:30 +0800] [Job 3] Added option Resolution D [21/May/2022:08:20:30 +0800] [Job 3] Added option MediaType D [21/May/2022:08:20:30 +0800] [Job 3] Added option Font D [21/May/2022:08:20:30 +0800] [Job 3] Parameter Summary D [21/May/2022:08:20:30 +0800] [Job 3] ----------------- D [21/May/2022:08:20:30 +0800] [Job 3] Spooler: cups D [21/May/2022:08:20:30 +0800] [Job 3] Printer: t20210525514lenovolj2400 D [21/May/2022:08:20:30 +0800] [Job 3] Shell: /bin/bash D [21/May/2022:08:20:30 +0800] [Job 3] PPD file: /etc/cups/ppd/t20210525514lenovolj2400.ppd D [21/May/2022:08:20:30 +0800] [Job 3] ATTR file: D [21/May/2022:08:20:30 +0800] [Job 3] Printer model: Brother HL-2240 Foomatic/hl1250 (recommended) D [21/May/2022:08:20:30 +0800] [Job 3] Job title: Test Page D [21/May/2022:08:20:30 +0800] [Job 3] File(s) to be printed: D [21/May/2022:08:20:30 +0800] [Job 3] D [21/May/2022:08:20:30 +0800] [Job 3] Ghostscript extra search path (\'GS_LIB\'): /usr/share/cups/fonts D [21/May/2022:08:20:30 +0800] [Job 3] Printing system options: D [21/May/2022:08:20:30 +0800] [Job 3] Pondering option \'job-uuid=urn:uuid:3a453ff0-70c3-32a2-5c0c-1004492676b8\' D [21/May/2022:08:20:30 +0800] [Job 3] Unknown option job-uuid=urn:uuid:3a453ff0-70c3-32a2-5c0c-1004492676b8. D [21/May/2022:08:20:30 +0800] [Job 3] Pondering option \'job-originating-host-name=localhost\' D [21/May/2022:08:20:30 +0800] [Job 3] Unknown option job-originating-host-name=localhost. D [21/May/2022:08:20:30 +0800] [Job 3] Pondering option \'date-time-at-creation=\' D [21/May/2022:08:20:30 +0800] [Job 3] Unknown option date-time-at-creation=. D [21/May/2022:08:20:30 +0800] [Job 3] Pondering option \'date-time-at-processing=\' D [21/May/2022:08:20:30 +0800] [Job 3] Unknown option date-time-at-processing=. D [21/May/2022:08:20:30 +0800] [Job 3] Pondering option \'time-at-creation=1653092426\' D [21/May/2022:08:20:30 +0800] [Job 3] Unknown option time-at-creation=1653092426. D [21/May/2022:08:20:30 +0800] [Job 3] Pondering option \'time-at-processing=1653092426\' D [21/May/2022:08:20:30 +0800] [Job 3] Unknown option time-at-processing=1653092426. D [21/May/2022:08:20:30 +0800] [Job 3] CM Color Calibration Mode in CUPS: Off D [21/May/2022:08:20:30 +0800] [Job 3] Options from the PPD file: D [21/May/2022:08:20:30 +0800] [Job 3] ================================================ D [21/May/2022:08:20:30 +0800] [Job 3] File: D [21/May/2022:08:20:30 +0800] [Job 3] ================================================ D [21/May/2022:08:20:30 +0800] [Job 3] Filetype: PDF D [21/May/2022:08:20:30 +0800] [Job 3] Storing temporary files in /var/spool/cups/tmp D [21/May/2022:08:20:30 +0800] [Job 3] sh: gs: 未找到命令 D [21/May/2022:08:20:30 +0800] [Job 3] Process is dying with \"Unable to determine number of pages, page count: -1 D [21/May/2022:08:20:30 +0800] [Job 3] \", exit stat 3 D [21/May/2022:08:20:30 +0800] [Job 3] Cleaning up... D [21/May/2022:08:20:30 +0800] [Job 3] PID 136428 (/usr/lib/cups/filter/foomatic-rip) stopped with status 3. D [21/May/2022:08:20:30 +0800] [Job 3] Hint: Try setting the LogLevel to "debug" to find out more. D [21/May/2022:08:20:30 +0800] [Job 3] STATE: -connecting-to-device D [21/May/2022:08:20:30 +0800] [Job 3] t20210525514lenovolj2400.xxx.test.com=101.122.103.231 D [21/May/2022:08:20:30 +0800] [Job 3] hrDeviceDesc=\"Unknown\" D [21/May/2022:08:20:30 +0800] [Job 3] prtGeneralCurrentLocalization type is 0, expected 2! D [21/May/2022:08:20:30 +0800] [Job 3] backendWaitLoop(snmp_fd=6, addr=0x5555bae19088, side_cb=0x5555b959bbb1) D [21/May/2022:08:20:30 +0800] [Job 3] PID 136429 (/usr/lib/cups/backend/ipp) exited with no errors. D [21/May/2022:08:20:30 +0800] [Job 3] End of messages D [21/May/2022:08:20:30 +0800] [Job 3] printer-state=3(idle) D [21/May/2022:08:20:30 +0800] [Job 3] printer-state-message="Filter failed" D [21/May/2022:08:20:30 +0800] [Job 3] printer-state-reasons=none E [21/May/2022:08:58:17 +0800] Unknown directive IdleExitTimeout on line 34 of /etc/cups/cupsd.conf. W [21/May/2022:08:58:17 +0800] Printer drivers are deprecated and will stop working in a future version of CUPS. See https://github.com/OpenPrinting/cups-sharing/issues/4 W [21/May/2022:08:58:17 +0800] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files W [21/May/2022:08:58:17 +0800] CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files I [21/May/2022:08:59:45 +0800] Listening to 0.0.0.0:6310 (IPv4) I [21/May/2022:08:59:45 +0800] Listening to [v1.::]:6310 (IPv6) I [21/May/2022:08:59:45 +0800] Listening to /var/run/cups/cups.sock (Domain) E [21/May/2022:08:59:45 +0800] Unknown directive IdleExitTimeout on line 13 of /etc/cups/cupsd.conf. I [21/May/2022:08:59:45 +0800] Remote access is enabled. D [21/May/2022:08:59:45 +0800] Added auto ServerAlias VM-12-2-centos I [21/May/2022:08:59:45 +0800] Loaded configuration file "/etc/cups/cupsd.conf" I [21/May/2022:08:59:45 +0800] Configured for up to 100 clients. I [21/May/2022:08:59:45 +0800] Allowing up to 100 client connections per host. I [21/May/2022:08:59:45 +0800] Using policy "default" as the default. D [21/May/2022:08:59:45 +0800] load_ppd: Loading /var/cache/cups/t20210525514lenovolj2400.data... D [21/May/2022:08:59:45 +0800] t20210525514lenovolj2400: pdl='application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster,image/urf' D [21/May/2022:08:59:45 +0800] cupsdRegisterPrinter(p=0x55fe0cfb7040(t20210525514lenovolj2400)) D [21/May/2022:08:59:45 +0800] cupsdMarkDirty(--p--) D [21/May/2022:08:59:45 +0800] cupsdSetBusyState: newbusy="Dirty files", busy="Not busy" I [21/May/2022:08:59:45 +0800] Partial reload complete. D [21/May/2022:08:59:45 +0800] Calling FindDeviceById(cups-t20210525514lenovolj2400) D [21/May/2022:08:59:45 +0800] FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files D [21/May/2022:08:59:45 +0800] Using profile ID "t20210525514lenovolj2400-Gray..". D [21/May/2022:08:59:45 +0800] Calling CreateProfile(t20210525514lenovolj2400-Gray..,temp) W [21/May/2022:08:59:45 +0800] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files I [21/May/2022:08:59:45 +0800] Registering ICC color profiles for "t20210525514lenovolj2400". D [21/May/2022:08:59:45 +0800] Calling CreateDevice(cups-t20210525514lenovolj2400,temp) W [21/May/2022:08:59:45 +0800] CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files I [21/May/2022:08:59:45 +0800] Listening to 0.0.0.0:6310 on fd 7... I [21/May/2022:08:59:45 +0800] Listening to [v1.::]:6310 on fd 9... I [21/May/2022:08:59:45 +0800] Listening to /var/run/cups/cups.sock on fd 10... I [21/May/2022:08:59:45 +0800] Resuming new connection processing... D [21/May/2022:08:59:45 +0800] Avahi server connection now available, registering printers for Bonjour broadcasting. I [21/May/2022:08:59:45 +0800] Defaulting to "DNSSDHostName VM-12-2-centos.local". D [21/May/2022:08:59:45 +0800] Registering "t20210525514lenovolj2400 @ VM-12-2-centos" with DNS-SD type "_printer._tcp". D [21/May/2022:08:59:45 +0800] Registering "t20210525514lenovolj2400 @ VM-12-2-centos" with DNS-SD type "_ipp._tcp". D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" printer-type=0x80B006 D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" mopria-certified=1.3 D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" URF=V1.4,CP1,W8,PQ4,RS75,FN3 D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" UUID=226a6ce8-6dea-30ff-6aa4-761185f348ee D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" pdl=application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster,image/urf D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" product=(HL-2240 series) D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" priority=0 D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" adminurl=http://VM-12-2-centos.local.:6310/printers/t20210525514lenovolj2400 D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" ty=Brother HL-2240 Foomatic/hl1250 (recommended) D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" rp=printers/t20210525514lenovolj2400 D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" qtotal=1 D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" txtvers=1 D [21/May/2022:08:59:45 +0800] Registering "t20210525514lenovolj2400 @ VM-12-2-centos" with DNS-SD type "_printer._tcp". D [21/May/2022:08:59:45 +0800] Registering "t20210525514lenovolj2400 @ VM-12-2-centos" with DNS-SD type "_ipp._tcp". D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" printer-type=0x80B006 D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" mopria-certified=1.3 D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" URF=V1.4,CP1,W8,PQ4,RS75,FN3 D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" UUID=226a6ce8-6dea-30ff-6aa4-761185f348ee D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" pdl=application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster,image/urf D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" product=(HL-2240 series) D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" priority=0 D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" adminurl=http://VM-12-2-centos.local.:6310/printers/t20210525514lenovolj2400 D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" ty=Brother HL-2240 Foomatic/hl1250 (recommended) D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" rp=printers/t20210525514lenovolj2400 D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" qtotal=1 D [21/May/2022:08:59:45 +0800] DNS_SD "t20210525514lenovolj2400 @ VM-12-2-centos" txtvers=1 D [21/May/2022:08:59:45 +0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/May/2022:08:59:45 +0800] Notifier dbus started - PID = 2633 D [21/May/2022:08:59:45 +0800] cupsdMarkDirty(----S) D [21/May/2022:08:59:45 +0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/May/2022:08:59:45 +0800] [Notifier] state=3 D [21/May/2022:08:59:45 +0800] Report: clients=0 D [21/May/2022:08:59:45 +0800] Report: jobs=2 D [21/May/2022:08:59:45 +0800] Report: jobs-active=2 D [21/May/2022:08:59:45 +0800] Report: printers=1 D [21/May/2022:08:59:45 +0800] Report: stringpool-string-count=847 D [21/May/2022:08:59:45 +0800] Report: stringpool-alloc-bytes=9192 D [21/May/2022:08:59:45 +0800] Report: stringpool-total-bytes=14152 D [21/May/2022:08:59:45 +0800] [Notifier] Connected to D-BUS D [21/May/2022:08:59:45 +0800] [Notifier] ServerRestarted I [21/May/2022:08:59:46 +0800] Expiring subscriptions... D [21/May/2022:08:59:51 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/May/2022:08:59:51 +0800] [Client 7] Server address is "10.0.12.2". D [21/May/2022:08:59:51 +0800] [Client 7] Accepted from 153.34.163.25:1851 (IPv4) D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for request. I [21/May/2022:08:59:51 +0800] Expiring subscriptions... D [21/May/2022:08:59:51 +0800] cupsdAddCert: Adding certificate for PID 0 D [21/May/2022:08:59:51 +0800] [Client 7] GET /printers/t20210525514lenovolj2400 HTTP/1.1 D [21/May/2022:08:59:51 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:51 +0800] [Client 7] Read: status=200, state=3 D [21/May/2022:08:59:51 +0800] [Client 7] No authentication data provided. D [21/May/2022:08:59:51 +0800] [Client 7] Processing GET /printers/t20210525514lenovolj2400 D [21/May/2022:08:59:51 +0800] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [21/May/2022:08:59:51 +0800] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [21/May/2022:08:59:51 +0800] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [21/May/2022:08:59:51 +0800] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/doc/cups" D [21/May/2022:08:59:51 +0800] [CGI] envp[3] = "CUPS_REQUESTROOT=/var/spool/cups" D [21/May/2022:08:59:51 +0800] [CGI] envp[4] = "CUPS_SERVERBIN=/usr/lib/cups" D [21/May/2022:08:59:51 +0800] [CGI] envp[5] = "CUPS_SERVERROOT=/etc/cups" D [21/May/2022:08:59:51 +0800] [CGI] envp[6] = "CUPS_STATEDIR=/var/run/cups" D [21/May/2022:08:59:51 +0800] [CGI] envp[7] = "HOME=/var/spool/cups/tmp" D [21/May/2022:08:59:51 +0800] [CGI] envp[8] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [21/May/2022:08:59:51 +0800] [CGI] envp[9] = "SERVER_ADMIN=root@VM-12-2-centos" D [21/May/2022:08:59:51 +0800] [CGI] envp[10] = "SOFTWARE=CUPS/2.4.1" D [21/May/2022:08:59:51 +0800] [CGI] envp[11] = "TMPDIR=/var/spool/cups/tmp" D [21/May/2022:08:59:51 +0800] [CGI] envp[12] = "USER=root" D [21/May/2022:08:59:51 +0800] [CGI] envp[13] = "CUPS_MAX_MESSAGE=2047" D [21/May/2022:08:59:51 +0800] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [21/May/2022:08:59:51 +0800] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [21/May/2022:08:59:51 +0800] [CGI] envp[16] = "IPP_PORT=6310" D [21/May/2022:08:59:51 +0800] [CGI] envp[17] = "LANG=zh_CN.UTF8" D [21/May/2022:08:59:51 +0800] [CGI] envp[18] = "REDIRECT_STATUS=1" D [21/May/2022:08:59:51 +0800] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1" D [21/May/2022:08:59:51 +0800] [CGI] envp[20] = "SERVER_NAME=xxx.test.com" D [21/May/2022:08:59:51 +0800] [CGI] envp[21] = "SERVER_PORT=6310" D [21/May/2022:08:59:51 +0800] [CGI] envp[22] = "REMOTE_ADDR=153.34.163.25" D [21/May/2022:08:59:51 +0800] [CGI] envp[23] = "REMOTE_HOST=153.34.163.25" D [21/May/2022:08:59:51 +0800] [CGI] envp[24] = "SCRIPT_NAME=/printers/t20210525514lenovolj2400" D [21/May/2022:08:59:51 +0800] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/doc/cups/printers/t20210525514lenovolj2400" D [21/May/2022:08:59:51 +0800] [CGI] envp[26] = "PATH_INFO=/t20210525514lenovolj2400" D [21/May/2022:08:59:51 +0800] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1" D [21/May/2022:08:59:51 +0800] [CGI] envp[28] = "HTTP_COOKIE=org.cups.sid=a9bd340c037825aa984a32b3397ddd91" D [21/May/2022:08:59:51 +0800] [CGI] envp[29] = "HTTP_USER_AGENT=Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/101.0.4951.64 Safari/537.36 Edg/101.0.1210.47" D [21/May/2022:08:59:51 +0800] [CGI] envp[30] = "HTTP_REFERER=http://xxx.test.com:6310/printers/" D [21/May/2022:08:59:51 +0800] [CGI] envp[31] = "REQUEST_METHOD=GET" D [21/May/2022:08:59:51 +0800] [CGI] envp[32] = "QUERY_STRING=" D [21/May/2022:08:59:51 +0800] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 2645) I [21/May/2022:08:59:51 +0800] [Client 7] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=2645, file=22) D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:51 +0800] [Client 8] Server address is "10.0.12.2". D [21/May/2022:08:59:51 +0800] [Client 8] Accepted from 153.34.163.25:1852 (IPv4) D [21/May/2022:08:59:51 +0800] [Client 8] Waiting for request. D [21/May/2022:08:59:51 +0800] [CGI] org.cups.sid cookie is \"a9bd340c037825aa984a32b3397ddd91\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetVariable: SECTION=\"printers\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetVariable: PRINTER_NAME=\"t20210525514lenovolj2400\" D [21/May/2022:08:59:51 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:51 +0800] [Client 9] Server address is "/var/run/cups/cups.sock". D [21/May/2022:08:59:51 +0800] [Client 9] Accepted from localhost (Domain) D [21/May/2022:08:59:51 +0800] [Client 9] Waiting for request. D [21/May/2022:08:59:51 +0800] [Client 9] POST / HTTP/1.1 D [21/May/2022:08:59:51 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:51 +0800] [Client 9] Read: status=200, state=6 D [21/May/2022:08:59:51 +0800] [Client 9] No authentication data provided. D [21/May/2022:08:59:51 +0800] [Client 9] 2.0 CUPS-Get-Default 1 D [21/May/2022:08:59:51 +0800] CUPS-Get-Default D [21/May/2022:08:59:51 +0800] CUPS-Get-Default client-error-not-found: 无默认打印机。 D [21/May/2022:08:59:51 +0800] [Client 9] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost. D [21/May/2022:08:59:51 +0800] [Client 9] Content-Length: 115 D [21/May/2022:08:59:51 +0800] [Client 9] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:08:59:51 +0800] [Client 9] con->http=0x55fe0d09a7e0 D [21/May/2022:08:59:51 +0800] [Client 9] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=115, response=0x55fe0d083720(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:08:59:51 +0800] [Client 9] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:08:59:51 +0800] [Client 9] bytes=0, http_state=0, data_remaining=115 D [21/May/2022:08:59:51 +0800] [Client 9] Flushing write buffer. D [21/May/2022:08:59:51 +0800] [Client 9] New state is HTTP_STATE_WAITING D [21/May/2022:08:59:51 +0800] [Client 9] Waiting for request. D [21/May/2022:08:59:51 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:51 +0800] [CGI] show_printer(http=0x556fdabe1e20, printer=\"t20210525514lenovolj2400\") D [21/May/2022:08:59:51 +0800] [Client 9] POST / HTTP/1.1 D [21/May/2022:08:59:51 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:51 +0800] [Client 9] Read: status=200, state=6 D [21/May/2022:08:59:51 +0800] [Client 9] No authentication data provided. D [21/May/2022:08:59:51 +0800] [Client 9] 2.0 Get-Printer-Attributes 2 D [21/May/2022:08:59:51 +0800] Get-Printer-Attributes ipp://localhost/printers/t20210525514lenovolj2400 D [21/May/2022:08:59:51 +0800] [Client 9] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/t20210525514lenovolj2400) from localhost. D [21/May/2022:08:59:51 +0800] [Client 9] Content-Length: 729 D [21/May/2022:08:59:51 +0800] [Client 9] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:08:59:51 +0800] [Client 9] con->http=0x55fe0d09a7e0 D [21/May/2022:08:59:51 +0800] [Client 9] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=729, response=0x55fe0d09e0b0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:08:59:51 +0800] [Client 9] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:08:59:51 +0800] [Client 9] bytes=0, http_state=0, data_remaining=729 D [21/May/2022:08:59:51 +0800] [Client 9] Flushing write buffer. D [21/May/2022:08:59:51 +0800] [Client 9] New state is HTTP_STATE_WAITING D [21/May/2022:08:59:51 +0800] [Client 9] Waiting for request. D [21/May/2022:08:59:51 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: printer_is_shared[0]=\"1\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: printer_state[0]=\"3\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: printer_name[0]=\"t20210525514lenovolj2400\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: printer_location[0]=\"\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: printer_info[0]=\"t20210525514lenovolj2400\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: device_uri[0]=\"ipp://t20210525514lenovolj2400.xxx.test.com:6318/xxx/xx\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: color_supported[0]=\"0\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: printer_make_and_model[0]=\"Brother HL-2240 Foomatic/hl1250 (recommended)\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: media_default[0]=\"iso_a4_210x297mm\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: sides_supported[0]=\"one-sided\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: sides_default[0]=\"one-sided\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: printer_commands[0]=\"AutoConfigure, Clean, PrintSelfTestPage\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"1\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetVariable: TITLE=\"t20210525514lenovolj2400\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:08:59:51 +0800] [Client 7] CGI data ready to be sent. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] Script header: Content-Type: text/html;charset=utf-8 D [21/May/2022:08:59:51 +0800] [Client 7] Script header: D [21/May/2022:08:59:51 +0800] [Client 7] Sending status 200 for CGI. D [21/May/2022:08:59:51 +0800] [Client 7] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] CGI data ready to be sent. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [CGI] Regular expression \".Clean.\" D [21/May/2022:08:59:51 +0800] [CGI] matches[0].rm_so=0 D [21/May/2022:08:59:51 +0800] [CGI] matches[1].rm_so=-1 D [21/May/2022:08:59:51 +0800] [CGI] Regular expression \".PrintSelfTestPage.\" D [21/May/2022:08:59:51 +0800] [CGI] matches[0].rm_so=0 D [21/May/2022:08:59:51 +0800] [CGI] matches[1].rm_so=-1 D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] CGI data ready to be sent. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] CGI data ready to be sent. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 9] POST / HTTP/1.1 D [21/May/2022:08:59:51 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:51 +0800] [Client 9] Read: status=200, state=6 D [21/May/2022:08:59:51 +0800] [Client 9] No authentication data provided. D [21/May/2022:08:59:51 +0800] [Client 9] 2.0 Get-Jobs 3 D [21/May/2022:08:59:51 +0800] Get-Jobs ipp://localhost:6310/printers/t20210525514lenovolj2400 D [21/May/2022:08:59:51 +0800] [Job 2] Loading attributes... D [21/May/2022:08:59:51 +0800] [Job 3] Loading attributes... D [21/May/2022:08:59:51 +0800] [Client 9] Returning IPP successful-ok for Get-Jobs (ipp://localhost:6310/printers/t20210525514lenovolj2400) from localhost. D [21/May/2022:08:59:51 +0800] [Client 9] Content-Length: 837 D [21/May/2022:08:59:51 +0800] [Client 9] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:08:59:51 +0800] [Client 9] con->http=0x55fe0d09a7e0 D [21/May/2022:08:59:51 +0800] [Client 9] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=837, response=0x55fe0d09ecd0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:08:59:51 +0800] [Client 9] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:08:59:51 +0800] [Client 9] bytes=0, http_state=0, data_remaining=837 D [21/May/2022:08:59:51 +0800] [Client 9] Flushing write buffer. D [21/May/2022:08:59:51 +0800] [Client 9] New state is HTTP_STATE_WAITING D [21/May/2022:08:59:51 +0800] [Client 9] Waiting for request. D [21/May/2022:08:59:51 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:51 +0800] [CGI] cgiClearVariables called. D [21/May/2022:08:59:51 +0800] [CGI] cgiSetVariable: SECTION=\"printers\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetVariable: TOTAL=\"2\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_preserved[0]=\"1\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_printer_name[0]=\"t20210525514lenovolj2400\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_printer_uri[0]=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_name[0]=\"Test Page\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_originating_user_name[0]=\"anonymous\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: time_at_completed[0]=\"novalue\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: time_at_creation[0]=\"2022年05月20日 星期五 21时31分13秒\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: time_at_processing[0]=\"2022年05月20日 星期五 21时54分42秒\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_id[0]=\"2\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_state[0]=\"6\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_impressions_completed[0]=\"3\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_k_octets[0]=\"1\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_hold_until[0]=\"no-hold\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_printer_state_message[0]=\"Filter failed\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_preserved[1]=\"1\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_printer_name[1]=\"t20210525514lenovolj2400\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_printer_uri[1]=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_name[1]=\"Test Page\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_originating_user_name[1]=\"anonymous\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: time_at_completed[1]=\"novalue\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: time_at_creation[1]=\"2022年05月21日 星期六 08时20分26秒\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: time_at_processing[1]=\"2022年05月21日 星期六 08时20分26秒\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_id[1]=\"3\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_state[1]=\"6\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_impressions_completed[1]=\"1\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_k_octets[1]=\"1\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_hold_until[1]=\"no-hold\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetArray: job_printer_state_message[1]=\"Filter failed\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetVariable: PRINTER_NAME=\"t20210525514lenovolj2400\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetVariable: THISURL=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:08:59:51 +0800] [CGI] cgiSetVariable: SEARCH_DEST=\"t20210525514lenovolj2400\" D [21/May/2022:08:59:51 +0800] [Client 7] CGI data ready to be sent. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] CGI data ready to be sent. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] CGI data ready to be sent. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] CGI data ready to be sent. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] CGI data ready to be sent. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] CGI data ready to be sent. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] CGI data ready to be sent. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] CGI data ready to be sent. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] CGI data ready to be sent. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] CGI data ready to be sent. D [21/May/2022:08:59:51 +0800] [Client 9] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [21/May/2022:08:59:51 +0800] [Client 9] Closing connection. D [21/May/2022:08:59:51 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] CGI data ready to be sent. D [21/May/2022:08:59:51 +0800] PID 2645 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [21/May/2022:08:59:51 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:51 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2645, file=22 D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:51 +0800] [Client 7] Sending 0-length chunk. D [21/May/2022:08:59:51 +0800] [Client 7] Flushing write buffer. D [21/May/2022:08:59:51 +0800] [Client 7] New state is HTTP_STATE_WAITING D [21/May/2022:08:59:51 +0800] [Client 7] Waiting for request. D [21/May/2022:08:59:51 +0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" I [21/May/2022:08:59:52 +0800] Expiring subscriptions... D [21/May/2022:08:59:54 +0800] [Client 7] POST /jobs/ HTTP/1.1 D [21/May/2022:08:59:54 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/May/2022:08:59:54 +0800] [Client 7] Read: status=200, state=6 D [21/May/2022:08:59:54 +0800] [Client 7] No authentication data provided. D [21/May/2022:08:59:54 +0800] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/jobs.cgi" D [21/May/2022:08:59:54 +0800] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [21/May/2022:08:59:54 +0800] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [21/May/2022:08:59:54 +0800] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/doc/cups" D [21/May/2022:08:59:54 +0800] [CGI] envp[3] = "CUPS_REQUESTROOT=/var/spool/cups" D [21/May/2022:08:59:54 +0800] [CGI] envp[4] = "CUPS_SERVERBIN=/usr/lib/cups" D [21/May/2022:08:59:54 +0800] [CGI] envp[5] = "CUPS_SERVERROOT=/etc/cups" D [21/May/2022:08:59:54 +0800] [CGI] envp[6] = "CUPS_STATEDIR=/var/run/cups" D [21/May/2022:08:59:54 +0800] [CGI] envp[7] = "HOME=/var/spool/cups/tmp" D [21/May/2022:08:59:54 +0800] [CGI] envp[8] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [21/May/2022:08:59:54 +0800] [CGI] envp[9] = "SERVER_ADMIN=root@VM-12-2-centos" D [21/May/2022:08:59:54 +0800] [CGI] envp[10] = "SOFTWARE=CUPS/2.4.1" D [21/May/2022:08:59:54 +0800] [CGI] envp[11] = "TMPDIR=/var/spool/cups/tmp" D [21/May/2022:08:59:54 +0800] [CGI] envp[12] = "USER=root" D [21/May/2022:08:59:54 +0800] [CGI] envp[13] = "CUPS_MAX_MESSAGE=2047" D [21/May/2022:08:59:54 +0800] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [21/May/2022:08:59:54 +0800] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [21/May/2022:08:59:54 +0800] [CGI] envp[16] = "IPP_PORT=6310" D [21/May/2022:08:59:54 +0800] [CGI] envp[17] = "LANG=zh_CN.UTF8" D [21/May/2022:08:59:54 +0800] [CGI] envp[18] = "REDIRECT_STATUS=1" D [21/May/2022:08:59:54 +0800] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1" D [21/May/2022:08:59:54 +0800] [CGI] envp[20] = "SERVER_NAME=xxx.test.com" D [21/May/2022:08:59:54 +0800] [CGI] envp[21] = "SERVER_PORT=6310" D [21/May/2022:08:59:54 +0800] [CGI] envp[22] = "REMOTE_ADDR=153.34.163.25" D [21/May/2022:08:59:54 +0800] [CGI] envp[23] = "REMOTE_HOST=153.34.163.25" D [21/May/2022:08:59:54 +0800] [CGI] envp[24] = "SCRIPT_NAME=/jobs/" D [21/May/2022:08:59:54 +0800] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/doc/cups/jobs/" D [21/May/2022:08:59:54 +0800] [CGI] envp[26] = "SERVER_PROTOCOL=HTTP/1.1" D [21/May/2022:08:59:54 +0800] [CGI] envp[27] = "HTTP_COOKIE=org.cups.sid=a9bd340c037825aa984a32b3397ddd91" D [21/May/2022:08:59:54 +0800] [CGI] envp[28] = "HTTP_USER_AGENT=Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/101.0.4951.64 Safari/537.36 Edg/101.0.1210.47" D [21/May/2022:08:59:54 +0800] [CGI] envp[29] = "HTTP_REFERER=http://xxx.test.com:6310/printers/t20210525514lenovolj2400" D [21/May/2022:08:59:54 +0800] [CGI] envp[30] = "REQUEST_METHOD=POST" D [21/May/2022:08:59:54 +0800] [CGI] envp[31] = "CONTENT_LENGTH=123" D [21/May/2022:08:59:54 +0800] [CGI] envp[32] = "CONTENT_TYPE=application/x-www-form-urlencoded" D [21/May/2022:08:59:54 +0800] [CGI] Started /usr/lib/cups/cgi-bin/jobs.cgi (PID 2652) I [21/May/2022:08:59:54 +0800] [Client 7] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=2652, file=24) D [21/May/2022:08:59:54 +0800] [Client 7] Waiting for CGI data. I [21/May/2022:08:59:54 +0800] Expiring subscriptions... D [21/May/2022:08:59:54 +0800] [CGI] org.cups.sid cookie is \"a9bd340c037825aa984a32b3397ddd91\" D [21/May/2022:08:59:54 +0800] [CGI] cgiSetVariable: org.cups.sid=\"a9bd340c037825aa984a32b3397ddd91\" D [21/May/2022:08:59:54 +0800] [CGI] cgiSetVariable: OP=\"cancel-job\" D [21/May/2022:08:59:54 +0800] [CGI] cgiSetVariable: job_id=\"3\" D [21/May/2022:08:59:54 +0800] [CGI] cgiSetVariable: job_printer_uri=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:08:59:54 +0800] [CGI] cgiSetVariable: SECTION=\"jobs\" D [21/May/2022:08:59:54 +0800] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [21/May/2022:08:59:54 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:54 +0800] [Client 10] Server address is "/var/run/cups/cups.sock". D [21/May/2022:08:59:54 +0800] [Client 10] Accepted from localhost (Domain) D [21/May/2022:08:59:54 +0800] [Client 10] Waiting for request. D [21/May/2022:08:59:54 +0800] [Client 10] POST /jobs HTTP/1.1 D [21/May/2022:08:59:54 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:54 +0800] [Client 10] Read: status=200, state=6 D [21/May/2022:08:59:54 +0800] [Client 10] No authentication data provided. D [21/May/2022:08:59:54 +0800] [Client 10] 2.0 Cancel-Job 1 D [21/May/2022:08:59:54 +0800] Cancel-Job ipp://localhost/jobs/3 D [21/May/2022:08:59:54 +0800] cupsdIsAuthorized: requesting-user-name="guest" D [21/May/2022:08:59:54 +0800] [Client 10] Returning HTTP Unauthorized for Cancel-Job (ipp://localhost/jobs/3) from localhost D [21/May/2022:08:59:54 +0800] [Client 10] cupsdSendHeader: code=401, type="text/html", auth_type=0 D [21/May/2022:08:59:54 +0800] [Client 10] WWW-Authenticate: Basic realm=\"CUPS\", PeerCred, Local trc=\"y\" D [21/May/2022:08:59:54 +0800] [CGI] cgi_passwd(prompt=\"请输入 localhost 上 lp 的密码 \") called! D [21/May/2022:08:59:54 +0800] [Client 7] CGI data ready to be sent. D [21/May/2022:08:59:54 +0800] [Client 7] con->http=0x55fe0d0917e0 D [21/May/2022:08:59:54 +0800] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=2652, file=24 D [21/May/2022:08:59:54 +0800] [Client 7] Waiting for CGI data. D [21/May/2022:08:59:54 +0800] [Client 7] Script header: Status: 401 D [21/May/2022:08:59:54 +0800] [Client 7] Script header: D [21/May/2022:08:59:54 +0800] [Client 7] Sending status 401 for CGI. D [21/May/2022:08:59:54 +0800] [Client 7] cupsdSendHeader: code=401, type="text/html", auth_type=0 D [21/May/2022:08:59:54 +0800] [Client 7] WWW-Authenticate: Basic realm=\"CUPS\" D [21/May/2022:08:59:54 +0800] [Client 7] Flushing write buffer. D [21/May/2022:08:59:54 +0800] [Client 7] New state is HTTP_STATE_WAITING D [21/May/2022:08:59:54 +0800] [Client 7] Waiting for request. D [21/May/2022:08:59:54 +0800] [Client 7] Closing because Keep-Alive is disabled. D [21/May/2022:08:59:54 +0800] [Client 7] Closing connection. D [21/May/2022:08:59:54 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:54 +0800] [Client 10] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [21/May/2022:08:59:54 +0800] [Client 10] Closing connection. D [21/May/2022:08:59:54 +0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:54 +0800] PID 2652 (/usr/lib/cups/cgi-bin/jobs.cgi) exited with no errors. D [21/May/2022:08:59:55 +0800] [Client 8] POST /jobs/ HTTP/1.1 D [21/May/2022:08:59:55 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/May/2022:08:59:55 +0800] [Client 8] Read: status=200, state=6 D [21/May/2022:08:59:55 +0800] [Client 8] Authorized as "root" using Basic. D [21/May/2022:08:59:55 +0800] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/jobs.cgi" D [21/May/2022:08:59:55 +0800] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [21/May/2022:08:59:55 +0800] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [21/May/2022:08:59:55 +0800] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/doc/cups" D [21/May/2022:08:59:55 +0800] [CGI] envp[3] = "CUPS_REQUESTROOT=/var/spool/cups" D [21/May/2022:08:59:55 +0800] [CGI] envp[4] = "CUPS_SERVERBIN=/usr/lib/cups" D [21/May/2022:08:59:55 +0800] [CGI] envp[5] = "CUPS_SERVERROOT=/etc/cups" D [21/May/2022:08:59:55 +0800] [CGI] envp[6] = "CUPS_STATEDIR=/var/run/cups" D [21/May/2022:08:59:55 +0800] [CGI] envp[7] = "HOME=/var/spool/cups/tmp" D [21/May/2022:08:59:55 +0800] [CGI] envp[8] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [21/May/2022:08:59:55 +0800] [CGI] envp[9] = "SERVER_ADMIN=root@VM-12-2-centos" D [21/May/2022:08:59:55 +0800] [CGI] envp[10] = "SOFTWARE=CUPS/2.4.1" D [21/May/2022:08:59:55 +0800] [CGI] envp[11] = "TMPDIR=/var/spool/cups/tmp" D [21/May/2022:08:59:55 +0800] [CGI] envp[12] = "USER=root" D [21/May/2022:08:59:55 +0800] [CGI] envp[13] = "CUPS_MAX_MESSAGE=2047" D [21/May/2022:08:59:55 +0800] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [21/May/2022:08:59:55 +0800] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [21/May/2022:08:59:55 +0800] [CGI] envp[16] = "IPP_PORT=6310" D [21/May/2022:08:59:55 +0800] [CGI] envp[17] = "AUTH_TYPE=Basic" D [21/May/2022:08:59:55 +0800] [CGI] envp[18] = "LANG=zh_CN.UTF8" D [21/May/2022:08:59:55 +0800] [CGI] envp[19] = "REDIRECT_STATUS=1" D [21/May/2022:08:59:55 +0800] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1" D [21/May/2022:08:59:55 +0800] [CGI] envp[21] = "SERVER_NAME=xxx.test.com" D [21/May/2022:08:59:55 +0800] [CGI] envp[22] = "SERVER_PORT=6310" D [21/May/2022:08:59:55 +0800] [CGI] envp[23] = "REMOTE_ADDR=153.34.163.25" D [21/May/2022:08:59:55 +0800] [CGI] envp[24] = "REMOTE_HOST=153.34.163.25" D [21/May/2022:08:59:55 +0800] [CGI] envp[25] = "SCRIPT_NAME=/jobs/" D [21/May/2022:08:59:55 +0800] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/doc/cups/jobs/" D [21/May/2022:08:59:55 +0800] [CGI] envp[27] = "REMOTE_USER=root" D [21/May/2022:08:59:55 +0800] [CGI] envp[28] = "SERVER_PROTOCOL=HTTP/1.1" D [21/May/2022:08:59:55 +0800] [CGI] envp[29] = "HTTP_COOKIE=org.cups.sid=a9bd340c037825aa984a32b3397ddd91" D [21/May/2022:08:59:55 +0800] [CGI] envp[30] = "HTTP_USER_AGENT=Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/101.0.4951.64 Safari/537.36 Edg/101.0.1210.47" D [21/May/2022:08:59:55 +0800] [CGI] envp[31] = "HTTP_REFERER=http://xxx.test.com:6310/printers/t20210525514lenovolj2400" D [21/May/2022:08:59:55 +0800] [CGI] envp[32] = "REQUEST_METHOD=POST" D [21/May/2022:08:59:55 +0800] [CGI] envp[33] = "CONTENT_LENGTH=123" D [21/May/2022:08:59:55 +0800] [CGI] envp[34] = "CONTENT_TYPE=application/x-www-form-urlencoded" D [21/May/2022:08:59:55 +0800] cupsdAddCert: Adding certificate for PID 2653 D [21/May/2022:08:59:55 +0800] [CGI] Started /usr/lib/cups/cgi-bin/jobs.cgi (PID 2653) I [21/May/2022:08:59:55 +0800] [Client 8] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=2653, file=22) D [21/May/2022:08:59:55 +0800] [Client 8] Waiting for CGI data. I [21/May/2022:08:59:55 +0800] Expiring subscriptions... D [21/May/2022:08:59:55 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:55 +0800] [Client 11] Server address is "10.0.12.2". D [21/May/2022:08:59:55 +0800] [Client 11] Accepted from 153.34.163.25:1868 (IPv4) D [21/May/2022:08:59:55 +0800] [Client 11] Waiting for request. D [21/May/2022:08:59:55 +0800] [CGI] org.cups.sid cookie is \"a9bd340c037825aa984a32b3397ddd91\" D [21/May/2022:08:59:55 +0800] [CGI] cgiSetVariable: org.cups.sid=\"a9bd340c037825aa984a32b3397ddd91\" D [21/May/2022:08:59:55 +0800] [CGI] cgiSetVariable: OP=\"cancel-job\" D [21/May/2022:08:59:55 +0800] [CGI] cgiSetVariable: job_id=\"3\" D [21/May/2022:08:59:55 +0800] [CGI] cgiSetVariable: job_printer_uri=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:08:59:55 +0800] [CGI] cgiSetVariable: SECTION=\"jobs\" D [21/May/2022:08:59:55 +0800] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [21/May/2022:08:59:55 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:55 +0800] [Client 12] Server address is "/var/run/cups/cups.sock". D [21/May/2022:08:59:55 +0800] [Client 12] Accepted from localhost (Domain) D [21/May/2022:08:59:55 +0800] [Client 12] Waiting for request. D [21/May/2022:08:59:55 +0800] [Client 12] POST /jobs HTTP/1.1 D [21/May/2022:08:59:55 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:55 +0800] [Client 12] Read: status=200, state=6 D [21/May/2022:08:59:55 +0800] [Client 12] No authentication data provided. D [21/May/2022:08:59:55 +0800] [Client 12] 2.0 Cancel-Job 1 D [21/May/2022:08:59:55 +0800] Cancel-Job ipp://localhost/jobs/3 D [21/May/2022:08:59:55 +0800] cupsdIsAuthorized: requesting-user-name="root" D [21/May/2022:08:59:55 +0800] [Job 3] time-at-completed=1653094795 D [21/May/2022:08:59:55 +0800] cupsdMarkDirty(----S) D [21/May/2022:08:59:55 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [21/May/2022:08:59:55 +0800] [Job 3] Job canceled by \"root\" I [21/May/2022:08:59:55 +0800] Expiring subscriptions... D [21/May/2022:08:59:55 +0800] cupsdMarkDirty(---J-) D [21/May/2022:08:59:55 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:55 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [21/May/2022:08:59:55 +0800] [Job 3] Canceled by "root". D [21/May/2022:08:59:55 +0800] [Client 12] Returning IPP successful-ok for Cancel-Job (ipp://localhost/jobs/3) from localhost. D [21/May/2022:08:59:55 +0800] [Client 12] Content-Length: 75 D [21/May/2022:08:59:55 +0800] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:08:59:55 +0800] [Client 12] con->http=0x55fe0d09a7e0 D [21/May/2022:08:59:55 +0800] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55fe0d09e780(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:08:59:55 +0800] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:08:59:55 +0800] [Client 12] bytes=0, http_state=0, data_remaining=75 D [21/May/2022:08:59:55 +0800] [Client 12] Flushing write buffer. D [21/May/2022:08:59:55 +0800] [Client 12] New state is HTTP_STATE_WAITING D [21/May/2022:08:59:55 +0800] [Client 12] Waiting for request. D [21/May/2022:08:59:55 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:55 +0800] [CGI] cgiSetVariable: refresh_page=\"5;URL=http://xxx.test.com:6310/printers/t20210525514lenovolj2400\" D [21/May/2022:08:59:55 +0800] [CGI] cgiSetVariable: TITLE=\"任务\" D [21/May/2022:08:59:55 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:08:59:55 +0800] [CGI] cgiSetVariable: REMOTE_USER=\"root\" D [21/May/2022:08:59:55 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:08:59:55 +0800] [Notifier] state=3 D [21/May/2022:08:59:55 +0800] [Notifier] JobCompleted D [21/May/2022:08:59:55 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:08:59:55 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:55 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=2653, file=22 D [21/May/2022:08:59:55 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:55 +0800] [Client 8] Script header: Content-Type: text/html;charset=utf-8 D [21/May/2022:08:59:55 +0800] [Client 8] Script header: D [21/May/2022:08:59:55 +0800] [Client 8] Sending status 200 for CGI. D [21/May/2022:08:59:55 +0800] [Client 8] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [21/May/2022:08:59:55 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:55 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2653, file=22 D [21/May/2022:08:59:55 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:55 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:08:59:55 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:55 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2653, file=22 D [21/May/2022:08:59:55 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:55 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:55 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2653, file=22 D [21/May/2022:08:59:55 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:55 +0800] [Client 12] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [21/May/2022:08:59:55 +0800] [Client 12] Closing connection. D [21/May/2022:08:59:55 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:55 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:08:59:55 +0800] PID 2653 (/usr/lib/cups/cgi-bin/jobs.cgi) exited with no errors. D [21/May/2022:08:59:55 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:55 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2653, file=22 D [21/May/2022:08:59:55 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:55 +0800] [Client 8] Sending 0-length chunk. D [21/May/2022:08:59:55 +0800] [Client 8] Flushing write buffer. D [21/May/2022:08:59:55 +0800] [Client 8] New state is HTTP_STATE_WAITING D [21/May/2022:08:59:55 +0800] [Client 8] Waiting for request. D [21/May/2022:08:59:55 +0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" I [21/May/2022:08:59:56 +0800] Expiring subscriptions... D [21/May/2022:08:59:57 +0800] [Client 8] GET /printers/ HTTP/1.1 D [21/May/2022:08:59:57 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/May/2022:08:59:57 +0800] [Client 8] Read: status=200, state=3 D [21/May/2022:08:59:57 +0800] [Client 8] No authentication data provided. D [21/May/2022:08:59:57 +0800] [Client 8] Processing GET /printers/ D [21/May/2022:08:59:57 +0800] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [21/May/2022:08:59:57 +0800] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [21/May/2022:08:59:57 +0800] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [21/May/2022:08:59:57 +0800] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/doc/cups" D [21/May/2022:08:59:57 +0800] [CGI] envp[3] = "CUPS_REQUESTROOT=/var/spool/cups" D [21/May/2022:08:59:57 +0800] [CGI] envp[4] = "CUPS_SERVERBIN=/usr/lib/cups" D [21/May/2022:08:59:57 +0800] [CGI] envp[5] = "CUPS_SERVERROOT=/etc/cups" D [21/May/2022:08:59:57 +0800] [CGI] envp[6] = "CUPS_STATEDIR=/var/run/cups" D [21/May/2022:08:59:57 +0800] [CGI] envp[7] = "HOME=/var/spool/cups/tmp" D [21/May/2022:08:59:57 +0800] [CGI] envp[8] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [21/May/2022:08:59:57 +0800] [CGI] envp[9] = "SERVER_ADMIN=root@VM-12-2-centos" D [21/May/2022:08:59:57 +0800] [CGI] envp[10] = "SOFTWARE=CUPS/2.4.1" D [21/May/2022:08:59:57 +0800] [CGI] envp[11] = "TMPDIR=/var/spool/cups/tmp" D [21/May/2022:08:59:57 +0800] [CGI] envp[12] = "USER=root" D [21/May/2022:08:59:57 +0800] [CGI] envp[13] = "CUPS_MAX_MESSAGE=2047" D [21/May/2022:08:59:57 +0800] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [21/May/2022:08:59:57 +0800] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [21/May/2022:08:59:57 +0800] [CGI] envp[16] = "IPP_PORT=6310" D [21/May/2022:08:59:57 +0800] [CGI] envp[17] = "LANG=zh_CN.UTF8" D [21/May/2022:08:59:57 +0800] [CGI] envp[18] = "REDIRECT_STATUS=1" D [21/May/2022:08:59:57 +0800] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1" D [21/May/2022:08:59:57 +0800] [CGI] envp[20] = "SERVER_NAME=xxx.test.com" D [21/May/2022:08:59:57 +0800] [CGI] envp[21] = "SERVER_PORT=6310" D [21/May/2022:08:59:57 +0800] [CGI] envp[22] = "REMOTE_ADDR=153.34.163.25" D [21/May/2022:08:59:57 +0800] [CGI] envp[23] = "REMOTE_HOST=153.34.163.25" D [21/May/2022:08:59:57 +0800] [CGI] envp[24] = "SCRIPT_NAME=/printers/" D [21/May/2022:08:59:57 +0800] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/doc/cups/printers/" D [21/May/2022:08:59:57 +0800] [CGI] envp[26] = "SERVER_PROTOCOL=HTTP/1.1" D [21/May/2022:08:59:57 +0800] [CGI] envp[27] = "HTTP_COOKIE=org.cups.sid=a9bd340c037825aa984a32b3397ddd91" D [21/May/2022:08:59:57 +0800] [CGI] envp[28] = "HTTP_USER_AGENT=Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/101.0.4951.64 Safari/537.36 Edg/101.0.1210.47" D [21/May/2022:08:59:57 +0800] [CGI] envp[29] = "HTTP_REFERER=http://xxx.test.com:6310/jobs/" D [21/May/2022:08:59:57 +0800] [CGI] envp[30] = "REQUEST_METHOD=GET" D [21/May/2022:08:59:57 +0800] [CGI] envp[31] = "QUERY_STRING=" D [21/May/2022:08:59:57 +0800] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 2655) I [21/May/2022:08:59:57 +0800] [Client 8] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=2655, file=22) D [21/May/2022:08:59:57 +0800] [Client 8] Waiting for CGI data. I [21/May/2022:08:59:57 +0800] Expiring subscriptions... D [21/May/2022:08:59:57 +0800] [CGI] org.cups.sid cookie is \"a9bd340c037825aa984a32b3397ddd91\" D [21/May/2022:08:59:57 +0800] [CGI] cgiSetVariable: SECTION=\"printers\" D [21/May/2022:08:59:57 +0800] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [21/May/2022:08:59:57 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:57 +0800] [Client 13] Server address is "/var/run/cups/cups.sock". D [21/May/2022:08:59:57 +0800] [Client 13] Accepted from localhost (Domain) D [21/May/2022:08:59:57 +0800] [Client 13] Waiting for request. D [21/May/2022:08:59:57 +0800] [Client 13] POST / HTTP/1.1 D [21/May/2022:08:59:57 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:57 +0800] [Client 13] Read: status=200, state=6 D [21/May/2022:08:59:57 +0800] [Client 13] No authentication data provided. D [21/May/2022:08:59:57 +0800] [Client 13] 2.0 CUPS-Get-Default 1 D [21/May/2022:08:59:57 +0800] CUPS-Get-Default D [21/May/2022:08:59:57 +0800] CUPS-Get-Default client-error-not-found: 无默认打印机。 D [21/May/2022:08:59:57 +0800] [Client 13] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost. D [21/May/2022:08:59:57 +0800] [Client 13] Content-Length: 115 D [21/May/2022:08:59:57 +0800] [Client 13] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:08:59:57 +0800] [Client 13] con->http=0x55fe0d09a7e0 D [21/May/2022:08:59:57 +0800] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=115, response=0x55fe0d086510(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:08:59:57 +0800] [Client 13] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:08:59:57 +0800] [Client 13] bytes=0, http_state=0, data_remaining=115 D [21/May/2022:08:59:57 +0800] [Client 13] Flushing write buffer. D [21/May/2022:08:59:57 +0800] [Client 13] New state is HTTP_STATE_WAITING D [21/May/2022:08:59:57 +0800] [Client 13] Waiting for request. D [21/May/2022:08:59:57 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:57 +0800] [CGI] show_all_printers(http=0x561ca9d67db0, user=\"(null)\") D [21/May/2022:08:59:57 +0800] [CGI] cgiSetVariable: TITLE=\"打印机\" D [21/May/2022:08:59:57 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:08:59:57 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:08:59:57 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:08:59:57 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:57 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=2655, file=22 D [21/May/2022:08:59:57 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:57 +0800] [Client 8] Script header: Content-Type: text/html;charset=utf-8 D [21/May/2022:08:59:57 +0800] [Client 8] Script header: D [21/May/2022:08:59:57 +0800] [Client 8] Sending status 200 for CGI. D [21/May/2022:08:59:57 +0800] [Client 8] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [21/May/2022:08:59:57 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:57 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2655, file=22 D [21/May/2022:08:59:57 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:57 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:08:59:57 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:57 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2655, file=22 D [21/May/2022:08:59:57 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:57 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:57 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2655, file=22 D [21/May/2022:08:59:57 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:57 +0800] [Client 13] POST / HTTP/1.1 D [21/May/2022:08:59:57 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:57 +0800] [Client 13] Read: status=200, state=6 D [21/May/2022:08:59:57 +0800] [Client 13] No authentication data provided. D [21/May/2022:08:59:57 +0800] [Client 13] 2.0 CUPS-Get-Printers 2 D [21/May/2022:08:59:57 +0800] CUPS-Get-Printers D [21/May/2022:08:59:57 +0800] [Client 13] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost. D [21/May/2022:08:59:57 +0800] [Client 13] Content-Length: 374 D [21/May/2022:08:59:57 +0800] [Client 13] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:08:59:57 +0800] [Client 13] con->http=0x55fe0d09a7e0 D [21/May/2022:08:59:57 +0800] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=374, response=0x55fe0d09e780(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:08:59:57 +0800] [Client 13] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:08:59:57 +0800] [Client 13] bytes=0, http_state=0, data_remaining=374 D [21/May/2022:08:59:57 +0800] [Client 13] Flushing write buffer. D [21/May/2022:08:59:57 +0800] [Client 13] New state is HTTP_STATE_WAITING D [21/May/2022:08:59:57 +0800] [Client 13] Waiting for request. D [21/May/2022:08:59:57 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:57 +0800] [CGI] cgiSetVariable: TOTAL=\"1\" D [21/May/2022:08:59:57 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:08:59:57 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:08:59:57 +0800] [CGI] cgiSetArray: printer_state[0]=\"3\" D [21/May/2022:08:59:57 +0800] [CGI] cgiSetArray: printer_state_message[0]=\"\" D [21/May/2022:08:59:57 +0800] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\" D [21/May/2022:08:59:57 +0800] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:08:59:57 +0800] [CGI] cgiSetArray: printer_name[0]=\"t20210525514lenovolj2400\" D [21/May/2022:08:59:57 +0800] [CGI] cgiSetArray: printer_location[0]=\"\" D [21/May/2022:08:59:57 +0800] [CGI] cgiSetArray: printer_info[0]=\"t20210525514lenovolj2400\" D [21/May/2022:08:59:57 +0800] [CGI] cgiSetArray: printer_make_and_model[0]=\"Brother HL-2240 Foomatic/hl1250 (recommended)\" D [21/May/2022:08:59:57 +0800] [CGI] cgiSetVariable: THISURL=\"/printers/\" D [21/May/2022:08:59:57 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:08:59:57 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:57 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2655, file=22 D [21/May/2022:08:59:57 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:57 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:57 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2655, file=22 D [21/May/2022:08:59:57 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:57 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:08:59:57 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:57 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2655, file=22 D [21/May/2022:08:59:57 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:57 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:57 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2655, file=22 D [21/May/2022:08:59:57 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:57 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:08:59:57 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:57 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2655, file=22 D [21/May/2022:08:59:57 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:57 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:57 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2655, file=22 D [21/May/2022:08:59:57 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:57 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:08:59:57 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:57 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2655, file=22 D [21/May/2022:08:59:57 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:57 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:57 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2655, file=22 D [21/May/2022:08:59:57 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:57 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:08:59:57 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:57 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2655, file=22 D [21/May/2022:08:59:57 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:57 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:57 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2655, file=22 D [21/May/2022:08:59:57 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:57 +0800] [Client 13] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [21/May/2022:08:59:57 +0800] [Client 13] Closing connection. D [21/May/2022:08:59:57 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:57 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:08:59:57 +0800] PID 2655 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [21/May/2022:08:59:57 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:57 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2655, file=22 D [21/May/2022:08:59:57 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:57 +0800] [Client 8] Sending 0-length chunk. D [21/May/2022:08:59:57 +0800] [Client 8] Flushing write buffer. D [21/May/2022:08:59:57 +0800] [Client 8] New state is HTTP_STATE_WAITING D [21/May/2022:08:59:57 +0800] [Client 8] Waiting for request. D [21/May/2022:08:59:57 +0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" I [21/May/2022:08:59:58 +0800] Expiring subscriptions... D [21/May/2022:08:59:59 +0800] [Client 8] GET /printers/t20210525514lenovolj2400 HTTP/1.1 D [21/May/2022:08:59:59 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/May/2022:08:59:59 +0800] [Client 8] Read: status=200, state=3 D [21/May/2022:08:59:59 +0800] [Client 8] No authentication data provided. D [21/May/2022:08:59:59 +0800] [Client 8] Processing GET /printers/t20210525514lenovolj2400 D [21/May/2022:08:59:59 +0800] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [21/May/2022:08:59:59 +0800] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [21/May/2022:08:59:59 +0800] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [21/May/2022:08:59:59 +0800] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/doc/cups" D [21/May/2022:08:59:59 +0800] [CGI] envp[3] = "CUPS_REQUESTROOT=/var/spool/cups" D [21/May/2022:08:59:59 +0800] [CGI] envp[4] = "CUPS_SERVERBIN=/usr/lib/cups" D [21/May/2022:08:59:59 +0800] [CGI] envp[5] = "CUPS_SERVERROOT=/etc/cups" D [21/May/2022:08:59:59 +0800] [CGI] envp[6] = "CUPS_STATEDIR=/var/run/cups" D [21/May/2022:08:59:59 +0800] [CGI] envp[7] = "HOME=/var/spool/cups/tmp" D [21/May/2022:08:59:59 +0800] [CGI] envp[8] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [21/May/2022:08:59:59 +0800] [CGI] envp[9] = "SERVER_ADMIN=root@VM-12-2-centos" D [21/May/2022:08:59:59 +0800] [CGI] envp[10] = "SOFTWARE=CUPS/2.4.1" D [21/May/2022:08:59:59 +0800] [CGI] envp[11] = "TMPDIR=/var/spool/cups/tmp" D [21/May/2022:08:59:59 +0800] [CGI] envp[12] = "USER=root" D [21/May/2022:08:59:59 +0800] [CGI] envp[13] = "CUPS_MAX_MESSAGE=2047" D [21/May/2022:08:59:59 +0800] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [21/May/2022:08:59:59 +0800] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [21/May/2022:08:59:59 +0800] [CGI] envp[16] = "IPP_PORT=6310" D [21/May/2022:08:59:59 +0800] [CGI] envp[17] = "LANG=zh_CN.UTF8" D [21/May/2022:08:59:59 +0800] [CGI] envp[18] = "REDIRECT_STATUS=1" D [21/May/2022:08:59:59 +0800] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1" D [21/May/2022:08:59:59 +0800] [CGI] envp[20] = "SERVER_NAME=xxx.test.com" D [21/May/2022:08:59:59 +0800] [CGI] envp[21] = "SERVER_PORT=6310" D [21/May/2022:08:59:59 +0800] [CGI] envp[22] = "REMOTE_ADDR=153.34.163.25" D [21/May/2022:08:59:59 +0800] [CGI] envp[23] = "REMOTE_HOST=153.34.163.25" D [21/May/2022:08:59:59 +0800] [CGI] envp[24] = "SCRIPT_NAME=/printers/t20210525514lenovolj2400" D [21/May/2022:08:59:59 +0800] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/doc/cups/printers/t20210525514lenovolj2400" D [21/May/2022:08:59:59 +0800] [CGI] envp[26] = "PATH_INFO=/t20210525514lenovolj2400" D [21/May/2022:08:59:59 +0800] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1" D [21/May/2022:08:59:59 +0800] [CGI] envp[28] = "HTTP_COOKIE=org.cups.sid=a9bd340c037825aa984a32b3397ddd91" D [21/May/2022:08:59:59 +0800] [CGI] envp[29] = "HTTP_USER_AGENT=Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/101.0.4951.64 Safari/537.36 Edg/101.0.1210.47" D [21/May/2022:08:59:59 +0800] [CGI] envp[30] = "HTTP_REFERER=http://xxx.test.com:6310/printers/" D [21/May/2022:08:59:59 +0800] [CGI] envp[31] = "REQUEST_METHOD=GET" D [21/May/2022:08:59:59 +0800] [CGI] envp[32] = "QUERY_STRING=" D [21/May/2022:08:59:59 +0800] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 2661) I [21/May/2022:08:59:59 +0800] [Client 8] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=2661, file=22) D [21/May/2022:08:59:59 +0800] [Client 8] Waiting for CGI data. I [21/May/2022:08:59:59 +0800] Expiring subscriptions... D [21/May/2022:08:59:59 +0800] [CGI] org.cups.sid cookie is \"a9bd340c037825aa984a32b3397ddd91\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetVariable: SECTION=\"printers\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetVariable: PRINTER_NAME=\"t20210525514lenovolj2400\" D [21/May/2022:08:59:59 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:59 +0800] [Client 14] Server address is "/var/run/cups/cups.sock". D [21/May/2022:08:59:59 +0800] [Client 14] Accepted from localhost (Domain) D [21/May/2022:08:59:59 +0800] [Client 14] Waiting for request. D [21/May/2022:08:59:59 +0800] [Client 14] POST / HTTP/1.1 D [21/May/2022:08:59:59 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:59 +0800] [Client 14] Read: status=200, state=6 D [21/May/2022:08:59:59 +0800] [Client 14] No authentication data provided. D [21/May/2022:08:59:59 +0800] [Client 14] 2.0 CUPS-Get-Default 1 D [21/May/2022:08:59:59 +0800] CUPS-Get-Default D [21/May/2022:08:59:59 +0800] CUPS-Get-Default client-error-not-found: 无默认打印机。 D [21/May/2022:08:59:59 +0800] [Client 14] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost. D [21/May/2022:08:59:59 +0800] [Client 14] Content-Length: 115 D [21/May/2022:08:59:59 +0800] [Client 14] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:08:59:59 +0800] [Client 14] con->http=0x55fe0d09a7e0 D [21/May/2022:08:59:59 +0800] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=115, response=0x55fe0d086510(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:08:59:59 +0800] [Client 14] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:08:59:59 +0800] [Client 14] bytes=0, http_state=0, data_remaining=115 D [21/May/2022:08:59:59 +0800] [Client 14] Flushing write buffer. D [21/May/2022:08:59:59 +0800] [Client 14] New state is HTTP_STATE_WAITING D [21/May/2022:08:59:59 +0800] [Client 14] Waiting for request. D [21/May/2022:08:59:59 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:59 +0800] [CGI] show_printer(http=0x5612b1712e20, printer=\"t20210525514lenovolj2400\") D [21/May/2022:08:59:59 +0800] [Client 14] POST / HTTP/1.1 D [21/May/2022:08:59:59 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:59 +0800] [Client 14] Read: status=200, state=6 D [21/May/2022:08:59:59 +0800] [Client 14] No authentication data provided. D [21/May/2022:08:59:59 +0800] [Client 14] 2.0 Get-Printer-Attributes 2 D [21/May/2022:08:59:59 +0800] Get-Printer-Attributes ipp://localhost/printers/t20210525514lenovolj2400 D [21/May/2022:08:59:59 +0800] [Client 14] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/t20210525514lenovolj2400) from localhost. D [21/May/2022:08:59:59 +0800] [Client 14] Content-Length: 729 D [21/May/2022:08:59:59 +0800] [Client 14] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:08:59:59 +0800] [Client 14] con->http=0x55fe0d09a7e0 D [21/May/2022:08:59:59 +0800] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=729, response=0x55fe0d0d0410(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:08:59:59 +0800] [Client 14] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:08:59:59 +0800] [Client 14] bytes=0, http_state=0, data_remaining=729 D [21/May/2022:08:59:59 +0800] [Client 14] Flushing write buffer. D [21/May/2022:08:59:59 +0800] [Client 14] New state is HTTP_STATE_WAITING D [21/May/2022:08:59:59 +0800] [Client 14] Waiting for request. D [21/May/2022:08:59:59 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: printer_is_shared[0]=\"1\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: printer_state[0]=\"3\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: printer_name[0]=\"t20210525514lenovolj2400\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: printer_location[0]=\"\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: printer_info[0]=\"t20210525514lenovolj2400\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: device_uri[0]=\"ipp://t20210525514lenovolj2400.xxx.test.com:6318/xxx/xx\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: color_supported[0]=\"0\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: printer_make_and_model[0]=\"Brother HL-2240 Foomatic/hl1250 (recommended)\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: media_default[0]=\"iso_a4_210x297mm\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: sides_supported[0]=\"one-sided\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: sides_default[0]=\"one-sided\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: printer_commands[0]=\"AutoConfigure, Clean, PrintSelfTestPage\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"1\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetVariable: TITLE=\"t20210525514lenovolj2400\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:08:59:59 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:08:59:59 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:59 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=2661, file=22 D [21/May/2022:08:59:59 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:59 +0800] [Client 8] Script header: Content-Type: text/html;charset=utf-8 D [21/May/2022:08:59:59 +0800] [Client 8] Script header: D [21/May/2022:08:59:59 +0800] [Client 8] Sending status 200 for CGI. D [21/May/2022:08:59:59 +0800] [Client 8] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [21/May/2022:08:59:59 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:59 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2661, file=22 D [21/May/2022:08:59:59 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:59 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:08:59:59 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:59 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2661, file=22 D [21/May/2022:08:59:59 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:59 +0800] [CGI] Regular expression \".Clean.\" D [21/May/2022:08:59:59 +0800] [CGI] matches[0].rm_so=0 D [21/May/2022:08:59:59 +0800] [CGI] matches[1].rm_so=-1 D [21/May/2022:08:59:59 +0800] [CGI] Regular expression \".PrintSelfTestPage.\" D [21/May/2022:08:59:59 +0800] [CGI] matches[0].rm_so=0 D [21/May/2022:08:59:59 +0800] [CGI] matches[1].rm_so=-1 D [21/May/2022:08:59:59 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:59 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2661, file=22 D [21/May/2022:08:59:59 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:59 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:08:59:59 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:59 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2661, file=22 D [21/May/2022:08:59:59 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:59 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:59 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2661, file=22 D [21/May/2022:08:59:59 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:59 +0800] [Client 14] POST / HTTP/1.1 D [21/May/2022:08:59:59 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:59 +0800] [Client 14] Read: status=200, state=6 D [21/May/2022:08:59:59 +0800] [Client 14] No authentication data provided. D [21/May/2022:08:59:59 +0800] [Client 14] 2.0 Get-Jobs 3 D [21/May/2022:08:59:59 +0800] Get-Jobs ipp://localhost:6310/printers/t20210525514lenovolj2400 D [21/May/2022:08:59:59 +0800] [Client 14] Returning IPP successful-ok for Get-Jobs (ipp://localhost:6310/printers/t20210525514lenovolj2400) from localhost. D [21/May/2022:08:59:59 +0800] [Client 14] Content-Length: 456 D [21/May/2022:08:59:59 +0800] [Client 14] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:08:59:59 +0800] [Client 14] con->http=0x55fe0d09a7e0 D [21/May/2022:08:59:59 +0800] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=456, response=0x55fe0d09e780(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:08:59:59 +0800] [Client 14] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:08:59:59 +0800] [Client 14] bytes=0, http_state=0, data_remaining=456 D [21/May/2022:08:59:59 +0800] [Client 14] Flushing write buffer. D [21/May/2022:08:59:59 +0800] [Client 14] New state is HTTP_STATE_WAITING D [21/May/2022:08:59:59 +0800] [Client 14] Waiting for request. D [21/May/2022:08:59:59 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:59 +0800] [CGI] cgiClearVariables called. D [21/May/2022:08:59:59 +0800] [CGI] cgiSetVariable: SECTION=\"printers\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetVariable: TOTAL=\"1\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: job_preserved[0]=\"1\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: job_printer_name[0]=\"t20210525514lenovolj2400\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: job_printer_uri[0]=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: job_name[0]=\"Test Page\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: job_originating_user_name[0]=\"anonymous\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: time_at_completed[0]=\"novalue\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: time_at_creation[0]=\"2022年05月20日 星期五 21时31分13秒\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: time_at_processing[0]=\"2022年05月20日 星期五 21时54分42秒\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: job_id[0]=\"2\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: job_state[0]=\"6\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: job_impressions_completed[0]=\"3\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: job_k_octets[0]=\"1\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: job_hold_until[0]=\"no-hold\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetArray: job_printer_state_message[0]=\"Filter failed\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetVariable: PRINTER_NAME=\"t20210525514lenovolj2400\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetVariable: THISURL=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:08:59:59 +0800] [CGI] cgiSetVariable: SEARCH_DEST=\"t20210525514lenovolj2400\" D [21/May/2022:08:59:59 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:08:59:59 +0800] [Client 14] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [21/May/2022:08:59:59 +0800] [Client 14] Closing connection. D [21/May/2022:08:59:59 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:08:59:59 +0800] PID 2661 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [21/May/2022:08:59:59 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:59 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2661, file=22 D [21/May/2022:08:59:59 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:59 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:59 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2661, file=22 D [21/May/2022:08:59:59 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:59 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:08:59:59 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:59 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2661, file=22 D [21/May/2022:08:59:59 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:59 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:59 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2661, file=22 D [21/May/2022:08:59:59 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:59 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:08:59:59 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:08:59:59 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2661, file=22 D [21/May/2022:08:59:59 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:08:59:59 +0800] [Client 8] Sending 0-length chunk. D [21/May/2022:08:59:59 +0800] [Client 8] Flushing write buffer. D [21/May/2022:08:59:59 +0800] [Client 8] New state is HTTP_STATE_WAITING D [21/May/2022:08:59:59 +0800] [Client 8] Waiting for request. D [21/May/2022:08:59:59 +0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:00 +0800] [Client 8] POST /jobs/ HTTP/1.1 D [21/May/2022:09:00:00 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/May/2022:09:00:00 +0800] [Client 8] Read: status=200, state=6 D [21/May/2022:09:00:00 +0800] [Client 8] Authorized as "root" using Basic. D [21/May/2022:09:00:00 +0800] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/jobs.cgi" D [21/May/2022:09:00:00 +0800] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [21/May/2022:09:00:00 +0800] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [21/May/2022:09:00:00 +0800] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/doc/cups" D [21/May/2022:09:00:00 +0800] [CGI] envp[3] = "CUPS_REQUESTROOT=/var/spool/cups" D [21/May/2022:09:00:00 +0800] [CGI] envp[4] = "CUPS_SERVERBIN=/usr/lib/cups" D [21/May/2022:09:00:00 +0800] [CGI] envp[5] = "CUPS_SERVERROOT=/etc/cups" D [21/May/2022:09:00:00 +0800] [CGI] envp[6] = "CUPS_STATEDIR=/var/run/cups" D [21/May/2022:09:00:00 +0800] [CGI] envp[7] = "HOME=/var/spool/cups/tmp" D [21/May/2022:09:00:00 +0800] [CGI] envp[8] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [21/May/2022:09:00:00 +0800] [CGI] envp[9] = "SERVER_ADMIN=root@VM-12-2-centos" D [21/May/2022:09:00:00 +0800] [CGI] envp[10] = "SOFTWARE=CUPS/2.4.1" D [21/May/2022:09:00:00 +0800] [CGI] envp[11] = "TMPDIR=/var/spool/cups/tmp" D [21/May/2022:09:00:00 +0800] [CGI] envp[12] = "USER=root" D [21/May/2022:09:00:00 +0800] [CGI] envp[13] = "CUPS_MAX_MESSAGE=2047" D [21/May/2022:09:00:00 +0800] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [21/May/2022:09:00:00 +0800] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [21/May/2022:09:00:00 +0800] [CGI] envp[16] = "IPP_PORT=6310" D [21/May/2022:09:00:00 +0800] [CGI] envp[17] = "AUTH_TYPE=Basic" D [21/May/2022:09:00:00 +0800] [CGI] envp[18] = "LANG=zh_CN.UTF8" D [21/May/2022:09:00:00 +0800] [CGI] envp[19] = "REDIRECT_STATUS=1" D [21/May/2022:09:00:00 +0800] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1" D [21/May/2022:09:00:00 +0800] [CGI] envp[21] = "SERVER_NAME=xxx.test.com" D [21/May/2022:09:00:00 +0800] [CGI] envp[22] = "SERVER_PORT=6310" D [21/May/2022:09:00:00 +0800] [CGI] envp[23] = "REMOTE_ADDR=153.34.163.25" D [21/May/2022:09:00:00 +0800] [CGI] envp[24] = "REMOTE_HOST=153.34.163.25" D [21/May/2022:09:00:00 +0800] [CGI] envp[25] = "SCRIPT_NAME=/jobs/" D [21/May/2022:09:00:00 +0800] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/doc/cups/jobs/" D [21/May/2022:09:00:00 +0800] [CGI] envp[27] = "REMOTE_USER=root" D [21/May/2022:09:00:00 +0800] [CGI] envp[28] = "SERVER_PROTOCOL=HTTP/1.1" D [21/May/2022:09:00:00 +0800] [CGI] envp[29] = "HTTP_COOKIE=org.cups.sid=a9bd340c037825aa984a32b3397ddd91" D [21/May/2022:09:00:00 +0800] [CGI] envp[30] = "HTTP_USER_AGENT=Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/101.0.4951.64 Safari/537.36 Edg/101.0.1210.47" D [21/May/2022:09:00:00 +0800] [CGI] envp[31] = "HTTP_REFERER=http://xxx.test.com:6310/printers/t20210525514lenovolj2400" D [21/May/2022:09:00:00 +0800] [CGI] envp[32] = "REQUEST_METHOD=POST" D [21/May/2022:09:00:00 +0800] [CGI] envp[33] = "CONTENT_LENGTH=123" D [21/May/2022:09:00:00 +0800] [CGI] envp[34] = "CONTENT_TYPE=application/x-www-form-urlencoded" D [21/May/2022:09:00:00 +0800] cupsdAddCert: Adding certificate for PID 2662 D [21/May/2022:09:00:00 +0800] [CGI] Started /usr/lib/cups/cgi-bin/jobs.cgi (PID 2662) I [21/May/2022:09:00:00 +0800] [Client 8] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=2662, file=24) D [21/May/2022:09:00:00 +0800] [Client 8] Waiting for CGI data. I [21/May/2022:09:00:00 +0800] Expiring subscriptions... D [21/May/2022:09:00:00 +0800] [CGI] org.cups.sid cookie is \"a9bd340c037825aa984a32b3397ddd91\" D [21/May/2022:09:00:00 +0800] [CGI] cgiSetVariable: org.cups.sid=\"a9bd340c037825aa984a32b3397ddd91\" D [21/May/2022:09:00:00 +0800] [CGI] cgiSetVariable: OP=\"cancel-job\" D [21/May/2022:09:00:00 +0800] [CGI] cgiSetVariable: job_id=\"2\" D [21/May/2022:09:00:00 +0800] [CGI] cgiSetVariable: job_printer_uri=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:09:00:00 +0800] [CGI] cgiSetVariable: SECTION=\"jobs\" D [21/May/2022:09:00:00 +0800] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [21/May/2022:09:00:00 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:00 +0800] [Client 15] Server address is "/var/run/cups/cups.sock". D [21/May/2022:09:00:00 +0800] [Client 15] Accepted from localhost (Domain) D [21/May/2022:09:00:00 +0800] [Client 15] Waiting for request. D [21/May/2022:09:00:00 +0800] [Client 15] POST /jobs HTTP/1.1 D [21/May/2022:09:00:00 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:00 +0800] [Client 15] Read: status=200, state=6 D [21/May/2022:09:00:00 +0800] [Client 15] No authentication data provided. D [21/May/2022:09:00:00 +0800] [Client 15] 2.0 Cancel-Job 1 D [21/May/2022:09:00:00 +0800] Cancel-Job ipp://localhost/jobs/2 D [21/May/2022:09:00:00 +0800] cupsdIsAuthorized: requesting-user-name="root" D [21/May/2022:09:00:00 +0800] [Job 2] time-at-completed=1653094800 D [21/May/2022:09:00:00 +0800] cupsdMarkDirty(----S) D [21/May/2022:09:00:00 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [21/May/2022:09:00:00 +0800] [Job 2] Job canceled by \"root\" I [21/May/2022:09:00:00 +0800] Expiring subscriptions... D [21/May/2022:09:00:00 +0800] cupsdMarkDirty(---J-) D [21/May/2022:09:00:00 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:00 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [21/May/2022:09:00:00 +0800] [Job 2] Canceled by "root". D [21/May/2022:09:00:00 +0800] [Client 15] Returning IPP successful-ok for Cancel-Job (ipp://localhost/jobs/2) from localhost. D [21/May/2022:09:00:00 +0800] [Client 15] Content-Length: 75 D [21/May/2022:09:00:00 +0800] [Client 15] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:09:00:00 +0800] [Client 15] con->http=0x55fe0d09a7e0 D [21/May/2022:09:00:00 +0800] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55fe0d089e70(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:09:00:00 +0800] [Client 15] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:09:00:00 +0800] [Client 15] bytes=0, http_state=0, data_remaining=75 D [21/May/2022:09:00:00 +0800] [Client 15] Flushing write buffer. D [21/May/2022:09:00:00 +0800] [Client 15] New state is HTTP_STATE_WAITING D [21/May/2022:09:00:00 +0800] [Client 15] Waiting for request. D [21/May/2022:09:00:00 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:00 +0800] [Notifier] state=3 D [21/May/2022:09:00:00 +0800] [Notifier] JobCompleted D [21/May/2022:09:00:00 +0800] [CGI] cgiSetVariable: refresh_page=\"5;URL=http://xxx.test.com:6310/printers/t20210525514lenovolj2400\" D [21/May/2022:09:00:00 +0800] [CGI] cgiSetVariable: TITLE=\"任务\" D [21/May/2022:09:00:00 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:09:00:00 +0800] [CGI] cgiSetVariable: REMOTE_USER=\"root\" D [21/May/2022:09:00:00 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:09:00:00 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:00 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:00 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=2662, file=24 D [21/May/2022:09:00:00 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:00 +0800] [Client 8] Script header: Content-Type: text/html;charset=utf-8 D [21/May/2022:09:00:00 +0800] [Client 8] Script header: D [21/May/2022:09:00:00 +0800] [Client 8] Sending status 200 for CGI. D [21/May/2022:09:00:00 +0800] [Client 8] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [21/May/2022:09:00:00 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:00 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2662, file=24 D [21/May/2022:09:00:00 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:00 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:00 +0800] [Client 15] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [21/May/2022:09:00:00 +0800] [Client 15] Closing connection. D [21/May/2022:09:00:00 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:00 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:00 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2662, file=24 D [21/May/2022:09:00:00 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:00 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:00 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2662, file=24 D [21/May/2022:09:00:00 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:00 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:00 +0800] PID 2662 (/usr/lib/cups/cgi-bin/jobs.cgi) exited with no errors. D [21/May/2022:09:00:00 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:00 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2662, file=24 D [21/May/2022:09:00:00 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:00 +0800] [Client 8] Sending 0-length chunk. D [21/May/2022:09:00:00 +0800] [Client 8] Flushing write buffer. D [21/May/2022:09:00:00 +0800] [Client 8] New state is HTTP_STATE_WAITING D [21/May/2022:09:00:00 +0800] [Client 8] Waiting for request. D [21/May/2022:09:00:00 +0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" I [21/May/2022:09:00:01 +0800] Expiring subscriptions... D [21/May/2022:09:00:01 +0800] [Client 8] GET /printers/ HTTP/1.1 D [21/May/2022:09:00:01 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/May/2022:09:00:01 +0800] [Client 8] Read: status=200, state=3 D [21/May/2022:09:00:01 +0800] [Client 8] No authentication data provided. D [21/May/2022:09:00:01 +0800] [Client 8] Processing GET /printers/ D [21/May/2022:09:00:01 +0800] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [21/May/2022:09:00:01 +0800] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [21/May/2022:09:00:01 +0800] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [21/May/2022:09:00:01 +0800] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/doc/cups" D [21/May/2022:09:00:01 +0800] [CGI] envp[3] = "CUPS_REQUESTROOT=/var/spool/cups" D [21/May/2022:09:00:01 +0800] [CGI] envp[4] = "CUPS_SERVERBIN=/usr/lib/cups" D [21/May/2022:09:00:01 +0800] [CGI] envp[5] = "CUPS_SERVERROOT=/etc/cups" D [21/May/2022:09:00:01 +0800] [CGI] envp[6] = "CUPS_STATEDIR=/var/run/cups" D [21/May/2022:09:00:01 +0800] [CGI] envp[7] = "HOME=/var/spool/cups/tmp" D [21/May/2022:09:00:01 +0800] [CGI] envp[8] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [21/May/2022:09:00:01 +0800] [CGI] envp[9] = "SERVER_ADMIN=root@VM-12-2-centos" D [21/May/2022:09:00:01 +0800] [CGI] envp[10] = "SOFTWARE=CUPS/2.4.1" D [21/May/2022:09:00:01 +0800] [CGI] envp[11] = "TMPDIR=/var/spool/cups/tmp" D [21/May/2022:09:00:01 +0800] [CGI] envp[12] = "USER=root" D [21/May/2022:09:00:01 +0800] [CGI] envp[13] = "CUPS_MAX_MESSAGE=2047" D [21/May/2022:09:00:01 +0800] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [21/May/2022:09:00:01 +0800] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [21/May/2022:09:00:01 +0800] [CGI] envp[16] = "IPP_PORT=6310" D [21/May/2022:09:00:01 +0800] [CGI] envp[17] = "LANG=zh_CN.UTF8" D [21/May/2022:09:00:01 +0800] [CGI] envp[18] = "REDIRECT_STATUS=1" D [21/May/2022:09:00:01 +0800] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1" D [21/May/2022:09:00:01 +0800] [CGI] envp[20] = "SERVER_NAME=xxx.test.com" D [21/May/2022:09:00:01 +0800] [CGI] envp[21] = "SERVER_PORT=6310" D [21/May/2022:09:00:01 +0800] [CGI] envp[22] = "REMOTE_ADDR=153.34.163.25" D [21/May/2022:09:00:01 +0800] [CGI] envp[23] = "REMOTE_HOST=153.34.163.25" D [21/May/2022:09:00:01 +0800] [CGI] envp[24] = "SCRIPT_NAME=/printers/" D [21/May/2022:09:00:01 +0800] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/doc/cups/printers/" D [21/May/2022:09:00:01 +0800] [CGI] envp[26] = "SERVER_PROTOCOL=HTTP/1.1" D [21/May/2022:09:00:01 +0800] [CGI] envp[27] = "HTTP_COOKIE=org.cups.sid=a9bd340c037825aa984a32b3397ddd91" D [21/May/2022:09:00:01 +0800] [CGI] envp[28] = "HTTP_USER_AGENT=Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/101.0.4951.64 Safari/537.36 Edg/101.0.1210.47" D [21/May/2022:09:00:01 +0800] [CGI] envp[29] = "HTTP_REFERER=http://xxx.test.com:6310/jobs/" D [21/May/2022:09:00:01 +0800] [CGI] envp[30] = "REQUEST_METHOD=GET" D [21/May/2022:09:00:01 +0800] [CGI] envp[31] = "QUERY_STRING=" D [21/May/2022:09:00:01 +0800] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 2744) I [21/May/2022:09:00:01 +0800] [Client 8] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=2744, file=22) D [21/May/2022:09:00:01 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:01 +0800] [CGI] org.cups.sid cookie is \"a9bd340c037825aa984a32b3397ddd91\" D [21/May/2022:09:00:01 +0800] [CGI] cgiSetVariable: SECTION=\"printers\" D [21/May/2022:09:00:01 +0800] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [21/May/2022:09:00:01 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:01 +0800] [Client 16] Server address is "/var/run/cups/cups.sock". D [21/May/2022:09:00:01 +0800] [Client 16] Accepted from localhost (Domain) D [21/May/2022:09:00:01 +0800] [Client 16] Waiting for request. D [21/May/2022:09:00:01 +0800] [Client 16] POST / HTTP/1.1 D [21/May/2022:09:00:01 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:01 +0800] [Client 16] Read: status=200, state=6 D [21/May/2022:09:00:01 +0800] [Client 16] No authentication data provided. D [21/May/2022:09:00:01 +0800] [Client 16] 2.0 CUPS-Get-Default 1 D [21/May/2022:09:00:01 +0800] CUPS-Get-Default D [21/May/2022:09:00:01 +0800] CUPS-Get-Default client-error-not-found: 无默认打印机。 D [21/May/2022:09:00:01 +0800] [Client 16] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost. D [21/May/2022:09:00:01 +0800] [Client 16] Content-Length: 115 D [21/May/2022:09:00:01 +0800] [Client 16] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:09:00:01 +0800] [Client 16] con->http=0x55fe0d09a7e0 D [21/May/2022:09:00:01 +0800] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=115, response=0x55fe0cf8e4f0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:09:00:01 +0800] [Client 16] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:09:00:01 +0800] [Client 16] bytes=0, http_state=0, data_remaining=115 D [21/May/2022:09:00:01 +0800] [Client 16] Flushing write buffer. D [21/May/2022:09:00:01 +0800] [Client 16] New state is HTTP_STATE_WAITING D [21/May/2022:09:00:01 +0800] [Client 16] Waiting for request. D [21/May/2022:09:00:01 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:01 +0800] [CGI] show_all_printers(http=0x55c7c6569db0, user=\"(null)\") D [21/May/2022:09:00:01 +0800] [CGI] cgiSetVariable: TITLE=\"打印机\" D [21/May/2022:09:00:01 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:09:00:01 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:09:00:01 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:01 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:01 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=2744, file=22 D [21/May/2022:09:00:01 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:01 +0800] [Client 8] Script header: Content-Type: text/html;charset=utf-8 D [21/May/2022:09:00:01 +0800] [Client 8] Script header: D [21/May/2022:09:00:01 +0800] [Client 8] Sending status 200 for CGI. D [21/May/2022:09:00:01 +0800] [Client 8] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [21/May/2022:09:00:01 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:01 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2744, file=22 D [21/May/2022:09:00:01 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:01 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:01 +0800] [Client 16] POST / HTTP/1.1 D [21/May/2022:09:00:01 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:01 +0800] [Client 16] Read: status=200, state=6 D [21/May/2022:09:00:01 +0800] [Client 16] No authentication data provided. D [21/May/2022:09:00:01 +0800] [Client 16] 2.0 CUPS-Get-Printers 2 D [21/May/2022:09:00:01 +0800] CUPS-Get-Printers D [21/May/2022:09:00:01 +0800] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost. D [21/May/2022:09:00:01 +0800] [Client 16] Content-Length: 374 D [21/May/2022:09:00:01 +0800] [Client 16] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:09:00:01 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:01 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2744, file=22 D [21/May/2022:09:00:01 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:01 +0800] [Client 16] con->http=0x55fe0d09a7e0 D [21/May/2022:09:00:01 +0800] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=374, response=0x55fe0d09de70(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:09:00:01 +0800] [Client 16] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:09:00:01 +0800] [Client 16] bytes=0, http_state=0, data_remaining=374 D [21/May/2022:09:00:01 +0800] [Client 16] Flushing write buffer. D [21/May/2022:09:00:01 +0800] [Client 16] New state is HTTP_STATE_WAITING D [21/May/2022:09:00:01 +0800] [Client 16] Waiting for request. D [21/May/2022:09:00:01 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:01 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:01 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2744, file=22 D [21/May/2022:09:00:01 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:01 +0800] [CGI] cgiSetVariable: TOTAL=\"1\" D [21/May/2022:09:00:01 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:09:00:01 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:09:00:01 +0800] [CGI] cgiSetArray: printer_state[0]=\"3\" D [21/May/2022:09:00:01 +0800] [CGI] cgiSetArray: printer_state_message[0]=\"\" D [21/May/2022:09:00:01 +0800] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\" D [21/May/2022:09:00:01 +0800] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:09:00:01 +0800] [CGI] cgiSetArray: printer_name[0]=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:01 +0800] [CGI] cgiSetArray: printer_location[0]=\"\" D [21/May/2022:09:00:01 +0800] [CGI] cgiSetArray: printer_info[0]=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:01 +0800] [CGI] cgiSetArray: printer_make_and_model[0]=\"Brother HL-2240 Foomatic/hl1250 (recommended)\" D [21/May/2022:09:00:01 +0800] [CGI] cgiSetVariable: THISURL=\"/printers/\" D [21/May/2022:09:00:01 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:01 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:01 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2744, file=22 D [21/May/2022:09:00:01 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:01 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:01 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2744, file=22 D [21/May/2022:09:00:01 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:01 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:01 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:01 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2744, file=22 D [21/May/2022:09:00:01 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:01 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:01 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2744, file=22 D [21/May/2022:09:00:01 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:01 +0800] [Client 16] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [21/May/2022:09:00:01 +0800] [Client 16] Closing connection. D [21/May/2022:09:00:01 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:01 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:01 +0800] PID 2744 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [21/May/2022:09:00:01 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:01 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2744, file=22 D [21/May/2022:09:00:01 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:01 +0800] [Client 8] Sending 0-length chunk. D [21/May/2022:09:00:01 +0800] [Client 8] Flushing write buffer. D [21/May/2022:09:00:01 +0800] [Client 8] New state is HTTP_STATE_WAITING D [21/May/2022:09:00:01 +0800] [Client 8] Waiting for request. D [21/May/2022:09:00:01 +0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" I [21/May/2022:09:00:02 +0800] Expiring subscriptions... D [21/May/2022:09:00:03 +0800] [Client 8] GET /printers/t20210525514lenovolj2400 HTTP/1.1 D [21/May/2022:09:00:03 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/May/2022:09:00:03 +0800] [Client 8] Read: status=200, state=3 D [21/May/2022:09:00:03 +0800] [Client 8] No authentication data provided. D [21/May/2022:09:00:03 +0800] [Client 8] Processing GET /printers/t20210525514lenovolj2400 D [21/May/2022:09:00:03 +0800] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [21/May/2022:09:00:03 +0800] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [21/May/2022:09:00:03 +0800] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [21/May/2022:09:00:03 +0800] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/doc/cups" D [21/May/2022:09:00:03 +0800] [CGI] envp[3] = "CUPS_REQUESTROOT=/var/spool/cups" D [21/May/2022:09:00:03 +0800] [CGI] envp[4] = "CUPS_SERVERBIN=/usr/lib/cups" D [21/May/2022:09:00:03 +0800] [CGI] envp[5] = "CUPS_SERVERROOT=/etc/cups" D [21/May/2022:09:00:03 +0800] [CGI] envp[6] = "CUPS_STATEDIR=/var/run/cups" D [21/May/2022:09:00:03 +0800] [CGI] envp[7] = "HOME=/var/spool/cups/tmp" D [21/May/2022:09:00:03 +0800] [CGI] envp[8] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [21/May/2022:09:00:03 +0800] [CGI] envp[9] = "SERVER_ADMIN=root@VM-12-2-centos" D [21/May/2022:09:00:03 +0800] [CGI] envp[10] = "SOFTWARE=CUPS/2.4.1" D [21/May/2022:09:00:03 +0800] [CGI] envp[11] = "TMPDIR=/var/spool/cups/tmp" D [21/May/2022:09:00:03 +0800] [CGI] envp[12] = "USER=root" D [21/May/2022:09:00:03 +0800] [CGI] envp[13] = "CUPS_MAX_MESSAGE=2047" D [21/May/2022:09:00:03 +0800] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [21/May/2022:09:00:03 +0800] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [21/May/2022:09:00:03 +0800] [CGI] envp[16] = "IPP_PORT=6310" D [21/May/2022:09:00:03 +0800] [CGI] envp[17] = "LANG=zh_CN.UTF8" D [21/May/2022:09:00:03 +0800] [CGI] envp[18] = "REDIRECT_STATUS=1" D [21/May/2022:09:00:03 +0800] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1" D [21/May/2022:09:00:03 +0800] [CGI] envp[20] = "SERVER_NAME=xxx.test.com" D [21/May/2022:09:00:03 +0800] [CGI] envp[21] = "SERVER_PORT=6310" D [21/May/2022:09:00:03 +0800] [CGI] envp[22] = "REMOTE_ADDR=153.34.163.25" D [21/May/2022:09:00:03 +0800] [CGI] envp[23] = "REMOTE_HOST=153.34.163.25" D [21/May/2022:09:00:03 +0800] [CGI] envp[24] = "SCRIPT_NAME=/printers/t20210525514lenovolj2400" D [21/May/2022:09:00:03 +0800] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/doc/cups/printers/t20210525514lenovolj2400" D [21/May/2022:09:00:03 +0800] [CGI] envp[26] = "PATH_INFO=/t20210525514lenovolj2400" D [21/May/2022:09:00:03 +0800] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1" D [21/May/2022:09:00:03 +0800] [CGI] envp[28] = "HTTP_COOKIE=org.cups.sid=a9bd340c037825aa984a32b3397ddd91" D [21/May/2022:09:00:03 +0800] [CGI] envp[29] = "HTTP_USER_AGENT=Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/101.0.4951.64 Safari/537.36 Edg/101.0.1210.47" D [21/May/2022:09:00:03 +0800] [CGI] envp[30] = "HTTP_REFERER=http://xxx.test.com:6310/printers/" D [21/May/2022:09:00:03 +0800] [CGI] envp[31] = "REQUEST_METHOD=GET" D [21/May/2022:09:00:03 +0800] [CGI] envp[32] = "QUERY_STRING=" D [21/May/2022:09:00:03 +0800] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 2763) I [21/May/2022:09:00:03 +0800] [Client 8] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=2763, file=22) D [21/May/2022:09:00:03 +0800] [Client 8] Waiting for CGI data. I [21/May/2022:09:00:03 +0800] Expiring subscriptions... D [21/May/2022:09:00:03 +0800] [CGI] org.cups.sid cookie is \"a9bd340c037825aa984a32b3397ddd91\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetVariable: SECTION=\"printers\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetVariable: PRINTER_NAME=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:03 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:03 +0800] [Client 17] Server address is "/var/run/cups/cups.sock". D [21/May/2022:09:00:03 +0800] [Client 17] Accepted from localhost (Domain) D [21/May/2022:09:00:03 +0800] [Client 17] Waiting for request. D [21/May/2022:09:00:03 +0800] [Client 17] POST / HTTP/1.1 D [21/May/2022:09:00:03 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:03 +0800] [Client 17] Read: status=200, state=6 D [21/May/2022:09:00:03 +0800] [Client 17] No authentication data provided. D [21/May/2022:09:00:03 +0800] [Client 17] 2.0 CUPS-Get-Default 1 D [21/May/2022:09:00:03 +0800] CUPS-Get-Default D [21/May/2022:09:00:03 +0800] CUPS-Get-Default client-error-not-found: 无默认打印机。 D [21/May/2022:09:00:03 +0800] [Client 17] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost. D [21/May/2022:09:00:03 +0800] [Client 17] Content-Length: 115 D [21/May/2022:09:00:03 +0800] [Client 17] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:09:00:03 +0800] [Client 17] con->http=0x55fe0d09a7e0 D [21/May/2022:09:00:03 +0800] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=115, response=0x55fe0d09e780(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:09:00:03 +0800] [Client 17] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:09:00:03 +0800] [Client 17] bytes=0, http_state=0, data_remaining=115 D [21/May/2022:09:00:03 +0800] [Client 17] Flushing write buffer. D [21/May/2022:09:00:03 +0800] [Client 17] New state is HTTP_STATE_WAITING D [21/May/2022:09:00:03 +0800] [Client 17] Waiting for request. D [21/May/2022:09:00:03 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:03 +0800] [CGI] show_printer(http=0x5584dcb70e20, printer=\"t20210525514lenovolj2400\") D [21/May/2022:09:00:03 +0800] [Client 17] POST / HTTP/1.1 D [21/May/2022:09:00:03 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:03 +0800] [Client 17] Read: status=200, state=6 D [21/May/2022:09:00:03 +0800] [Client 17] No authentication data provided. D [21/May/2022:09:00:03 +0800] [Client 17] 2.0 Get-Printer-Attributes 2 D [21/May/2022:09:00:03 +0800] Get-Printer-Attributes ipp://localhost/printers/t20210525514lenovolj2400 D [21/May/2022:09:00:03 +0800] [Client 17] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/t20210525514lenovolj2400) from localhost. D [21/May/2022:09:00:03 +0800] [Client 17] Content-Length: 729 D [21/May/2022:09:00:03 +0800] [Client 17] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:09:00:03 +0800] [Client 17] con->http=0x55fe0d09a7e0 D [21/May/2022:09:00:03 +0800] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=729, response=0x55fe0d0d0410(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:09:00:03 +0800] [Client 17] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:09:00:03 +0800] [Client 17] bytes=0, http_state=0, data_remaining=729 D [21/May/2022:09:00:03 +0800] [Client 17] Flushing write buffer. D [21/May/2022:09:00:03 +0800] [Client 17] New state is HTTP_STATE_WAITING D [21/May/2022:09:00:03 +0800] [Client 17] Waiting for request. D [21/May/2022:09:00:03 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetArray: printer_is_shared[0]=\"1\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetArray: printer_state[0]=\"3\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetArray: printer_name[0]=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetArray: printer_location[0]=\"\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetArray: printer_info[0]=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetArray: device_uri[0]=\"ipp://t20210525514lenovolj2400.xxx.test.com:6318/xxx/xx\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetArray: color_supported[0]=\"0\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetArray: printer_make_and_model[0]=\"Brother HL-2240 Foomatic/hl1250 (recommended)\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetArray: media_default[0]=\"iso_a4_210x297mm\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetArray: sides_supported[0]=\"one-sided\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetArray: sides_default[0]=\"one-sided\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetArray: printer_commands[0]=\"AutoConfigure, Clean, PrintSelfTestPage\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"1\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetVariable: TITLE=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:09:00:03 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:03 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:03 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=2763, file=22 D [21/May/2022:09:00:03 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:03 +0800] [Client 8] Script header: Content-Type: text/html;charset=utf-8 D [21/May/2022:09:00:03 +0800] [Client 8] Script header: D [21/May/2022:09:00:03 +0800] [Client 8] Sending status 200 for CGI. D [21/May/2022:09:00:03 +0800] [Client 8] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [21/May/2022:09:00:03 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:03 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2763, file=22 D [21/May/2022:09:00:03 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:03 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:03 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:03 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2763, file=22 D [21/May/2022:09:00:03 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:03 +0800] [CGI] Regular expression \".Clean.\" D [21/May/2022:09:00:03 +0800] [CGI] matches[0].rm_so=0 D [21/May/2022:09:00:03 +0800] [CGI] matches[1].rm_so=-1 D [21/May/2022:09:00:03 +0800] [CGI] Regular expression \".PrintSelfTestPage.\" D [21/May/2022:09:00:03 +0800] [CGI] matches[0].rm_so=0 D [21/May/2022:09:00:03 +0800] [CGI] matches[1].rm_so=-1 D [21/May/2022:09:00:03 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:03 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2763, file=22 D [21/May/2022:09:00:03 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:03 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:03 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:03 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2763, file=22 D [21/May/2022:09:00:03 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:03 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:03 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2763, file=22 D [21/May/2022:09:00:03 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:03 +0800] [Client 17] POST / HTTP/1.1 D [21/May/2022:09:00:03 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:03 +0800] [Client 17] Read: status=200, state=6 D [21/May/2022:09:00:03 +0800] [Client 17] No authentication data provided. D [21/May/2022:09:00:03 +0800] [Client 17] 2.0 Get-Jobs 3 D [21/May/2022:09:00:03 +0800] Get-Jobs ipp://localhost:6310/printers/t20210525514lenovolj2400 D [21/May/2022:09:00:03 +0800] [Client 17] Returning IPP successful-ok for Get-Jobs (ipp://localhost:6310/printers/t20210525514lenovolj2400) from localhost. D [21/May/2022:09:00:03 +0800] [Client 17] Content-Length: 75 D [21/May/2022:09:00:03 +0800] [Client 17] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:09:00:03 +0800] [Client 17] con->http=0x55fe0d09a7e0 D [21/May/2022:09:00:03 +0800] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55fe0d09de70(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:09:00:03 +0800] [Client 17] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:09:00:03 +0800] [Client 17] bytes=0, http_state=0, data_remaining=75 D [21/May/2022:09:00:03 +0800] [Client 17] Flushing write buffer. D [21/May/2022:09:00:03 +0800] [Client 17] New state is HTTP_STATE_WAITING D [21/May/2022:09:00:03 +0800] [Client 17] Waiting for request. D [21/May/2022:09:00:03 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:03 +0800] [CGI] cgiClearVariables called. D [21/May/2022:09:00:03 +0800] [CGI] cgiSetVariable: SECTION=\"printers\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetVariable: TOTAL=\"0\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetVariable: PRINTER_NAME=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetVariable: THISURL=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:09:00:03 +0800] [CGI] cgiSetVariable: SEARCH_DEST=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:03 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:03 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:03 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2763, file=22 D [21/May/2022:09:00:03 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:03 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:03 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2763, file=22 D [21/May/2022:09:00:03 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:03 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:03 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:03 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2763, file=22 D [21/May/2022:09:00:03 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:03 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:03 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2763, file=22 D [21/May/2022:09:00:03 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:03 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:03 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:03 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2763, file=22 D [21/May/2022:09:00:03 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:03 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:03 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2763, file=22 D [21/May/2022:09:00:03 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:03 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:03 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:03 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2763, file=22 D [21/May/2022:09:00:03 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:03 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:03 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2763, file=22 D [21/May/2022:09:00:03 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:03 +0800] [Client 17] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [21/May/2022:09:00:03 +0800] [Client 17] Closing connection. D [21/May/2022:09:00:03 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:03 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:03 +0800] PID 2763 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [21/May/2022:09:00:03 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:03 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2763, file=22 D [21/May/2022:09:00:03 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:03 +0800] [Client 8] Sending 0-length chunk. D [21/May/2022:09:00:03 +0800] [Client 8] Flushing write buffer. D [21/May/2022:09:00:03 +0800] [Client 8] New state is HTTP_STATE_WAITING D [21/May/2022:09:00:03 +0800] [Client 8] Waiting for request. D [21/May/2022:09:00:03 +0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" I [21/May/2022:09:00:04 +0800] Expiring subscriptions... D [21/May/2022:09:00:05 +0800] [Client 8] POST /printers/t20210525514lenovolj2400 HTTP/1.1 D [21/May/2022:09:00:05 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [21/May/2022:09:00:05 +0800] [Client 8] Read: status=200, state=6 D [21/May/2022:09:00:05 +0800] [Client 8] No authentication data provided. D [21/May/2022:09:00:05 +0800] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [21/May/2022:09:00:05 +0800] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [21/May/2022:09:00:05 +0800] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [21/May/2022:09:00:05 +0800] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/doc/cups" D [21/May/2022:09:00:05 +0800] [CGI] envp[3] = "CUPS_REQUESTROOT=/var/spool/cups" D [21/May/2022:09:00:05 +0800] [CGI] envp[4] = "CUPS_SERVERBIN=/usr/lib/cups" D [21/May/2022:09:00:05 +0800] [CGI] envp[5] = "CUPS_SERVERROOT=/etc/cups" D [21/May/2022:09:00:05 +0800] [CGI] envp[6] = "CUPS_STATEDIR=/var/run/cups" D [21/May/2022:09:00:05 +0800] [CGI] envp[7] = "HOME=/var/spool/cups/tmp" D [21/May/2022:09:00:05 +0800] [CGI] envp[8] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [21/May/2022:09:00:05 +0800] [CGI] envp[9] = "SERVER_ADMIN=root@VM-12-2-centos" D [21/May/2022:09:00:05 +0800] [CGI] envp[10] = "SOFTWARE=CUPS/2.4.1" D [21/May/2022:09:00:05 +0800] [CGI] envp[11] = "TMPDIR=/var/spool/cups/tmp" D [21/May/2022:09:00:05 +0800] [CGI] envp[12] = "USER=root" D [21/May/2022:09:00:05 +0800] [CGI] envp[13] = "CUPS_MAX_MESSAGE=2047" D [21/May/2022:09:00:05 +0800] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [21/May/2022:09:00:05 +0800] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [21/May/2022:09:00:05 +0800] [CGI] envp[16] = "IPP_PORT=6310" D [21/May/2022:09:00:05 +0800] [CGI] envp[17] = "LANG=zh_CN.UTF8" D [21/May/2022:09:00:05 +0800] [CGI] envp[18] = "REDIRECT_STATUS=1" D [21/May/2022:09:00:05 +0800] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1" D [21/May/2022:09:00:05 +0800] [CGI] envp[20] = "SERVER_NAME=xxx.test.com" D [21/May/2022:09:00:05 +0800] [CGI] envp[21] = "SERVER_PORT=6310" D [21/May/2022:09:00:05 +0800] [CGI] envp[22] = "REMOTE_ADDR=153.34.163.25" D [21/May/2022:09:00:05 +0800] [CGI] envp[23] = "REMOTE_HOST=153.34.163.25" D [21/May/2022:09:00:05 +0800] [CGI] envp[24] = "SCRIPT_NAME=/printers/t20210525514lenovolj2400" D [21/May/2022:09:00:05 +0800] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/doc/cups/printers/t20210525514lenovolj2400" D [21/May/2022:09:00:05 +0800] [CGI] envp[26] = "PATH_INFO=/t20210525514lenovolj2400" D [21/May/2022:09:00:05 +0800] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1" D [21/May/2022:09:00:05 +0800] [CGI] envp[28] = "HTTP_COOKIE=org.cups.sid=a9bd340c037825aa984a32b3397ddd91" D [21/May/2022:09:00:05 +0800] [CGI] envp[29] = "HTTP_USER_AGENT=Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/101.0.4951.64 Safari/537.36 Edg/101.0.1210.47" D [21/May/2022:09:00:05 +0800] [CGI] envp[30] = "HTTP_REFERER=http://xxx.test.com:6310/printers/t20210525514lenovolj2400" D [21/May/2022:09:00:05 +0800] [CGI] envp[31] = "REQUEST_METHOD=POST" D [21/May/2022:09:00:05 +0800] [CGI] envp[32] = "CONTENT_LENGTH=64" D [21/May/2022:09:00:05 +0800] [CGI] envp[33] = "CONTENT_TYPE=application/x-www-form-urlencoded" D [21/May/2022:09:00:05 +0800] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 2766) I [21/May/2022:09:00:05 +0800] [Client 8] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=2766, file=24) D [21/May/2022:09:00:05 +0800] [Client 8] Waiting for CGI data. I [21/May/2022:09:00:05 +0800] Expiring subscriptions... D [21/May/2022:09:00:05 +0800] [CGI] org.cups.sid cookie is \"a9bd340c037825aa984a32b3397ddd91\" D [21/May/2022:09:00:05 +0800] [CGI] cgiSetVariable: org.cups.sid=\"a9bd340c037825aa984a32b3397ddd91\" D [21/May/2022:09:00:05 +0800] [CGI] cgiSetVariable: OP=\"print-test-page\" D [21/May/2022:09:00:05 +0800] [CGI] cgiSetVariable: SECTION=\"printers\" D [21/May/2022:09:00:05 +0800] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [21/May/2022:09:00:05 +0800] [CGI] cgiSetVariable: PRINTER_NAME=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:05 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:05 +0800] [Client 18] Server address is "/var/run/cups/cups.sock". D [21/May/2022:09:00:05 +0800] [Client 18] Accepted from localhost (Domain) D [21/May/2022:09:00:05 +0800] [Client 18] Waiting for request. D [21/May/2022:09:00:05 +0800] [Client 18] POST /printers/t20210525514lenovolj2400 HTTP/1.1 D [21/May/2022:09:00:05 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:05 +0800] [Client 18] Read: status=200, state=6 D [21/May/2022:09:00:05 +0800] [Client 18] No authentication data provided. D [21/May/2022:09:00:05 +0800] [Client 18] 2.0 Print-Job 1 D [21/May/2022:09:00:05 +0800] [Client 18] Read: status=100, state=6 D [21/May/2022:09:00:05 +0800] Print-Job ipp://localhost:6310/printers/t20210525514lenovolj2400 D [21/May/2022:09:00:05 +0800] [Job ???] Auto-typing file... I [21/May/2022:09:00:05 +0800] [Job ???] Request file type is application/vnd.cups-pdf-banner. D [21/May/2022:09:00:05 +0800] cupsdMarkDirty(---J-) D [21/May/2022:09:00:05 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:05 +0800] [Job 4] Applying default options... D [21/May/2022:09:00:05 +0800] Adding default job-sheets values "none,none"... I [21/May/2022:09:00:05 +0800] [Job 4] Adding start banner page "none". D [21/May/2022:09:00:05 +0800] cupsdMarkDirty(----S) D [21/May/2022:09:00:05 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:05 +0800] cupsdMarkDirty(---J-) D [21/May/2022:09:00:05 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [21/May/2022:09:00:05 +0800] [Job 4] Adding end banner page "none". I [21/May/2022:09:00:05 +0800] [Job 4] File of type application/vnd.cups-pdf-banner queued by "anonymous". D [21/May/2022:09:00:05 +0800] [Job 4] hold_until=0 I [21/May/2022:09:00:05 +0800] [Job 4] Queued on "t20210525514lenovolj2400" by "anonymous". D [21/May/2022:09:00:05 +0800] [Job 4] time-at-processing=1653094805 D [21/May/2022:09:00:05 +0800] cupsdMarkDirty(---J-) D [21/May/2022:09:00:05 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:05 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:05 +0800] cupsdMarkDirty(----S) D [21/May/2022:09:00:05 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:05 +0800] [Job 4] 3 filters for job: D [21/May/2022:09:00:05 +0800] [Job 4] bannertopdf (application/vnd.cups-pdf-banner to application/pdf, cost 32) D [21/May/2022:09:00:05 +0800] [Job 4] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66) D [21/May/2022:09:00:05 +0800] [Job 4] foomatic-rip (application/vnd.cups-pdf to printer/t20210525514lenovolj2400, cost 0) D [21/May/2022:09:00:05 +0800] [Job 4] job-sheets=none,none D [21/May/2022:09:00:05 +0800] [Job 4] argv[0]="t20210525514lenovolj2400" D [21/May/2022:09:00:05 +0800] [Job 4] argv[1]="4" D [21/May/2022:09:00:05 +0800] [Job 4] argv[2]="anonymous" D [21/May/2022:09:00:05 +0800] [Job 4] argv[3]="Test Page" D [21/May/2022:09:00:05 +0800] [Job 4] argv[4]="1" D [21/May/2022:09:00:05 +0800] [Job 4] argv[5]="job-uuid=urn:uuid:d58dc47d-b1da-3d92-4059-1b9c706f80a7 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1653094805 time-at-processing=1653094805" D [21/May/2022:09:00:05 +0800] [Job 4] argv[6]="/var/spool/cups/d00004-001" D [21/May/2022:09:00:05 +0800] [Job 4] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [21/May/2022:09:00:05 +0800] [Job 4] envp[1]="CUPS_DATADIR=/usr/share/cups" D [21/May/2022:09:00:05 +0800] [Job 4] envp[2]="CUPS_DOCROOT=/usr/share/doc/cups" D [21/May/2022:09:00:05 +0800] [Job 4] envp[3]="CUPS_REQUESTROOT=/var/spool/cups" D [21/May/2022:09:00:05 +0800] [Job 4] envp[4]="CUPS_SERVERBIN=/usr/lib/cups" D [21/May/2022:09:00:05 +0800] [Job 4] envp[5]="CUPS_SERVERROOT=/etc/cups" D [21/May/2022:09:00:05 +0800] [Job 4] envp[6]="CUPS_STATEDIR=/var/run/cups" D [21/May/2022:09:00:05 +0800] [Job 4] envp[7]="HOME=/var/spool/cups/tmp" D [21/May/2022:09:00:05 +0800] [Job 4] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [21/May/2022:09:00:05 +0800] [Job 4] envp[9]="SERVER_ADMIN=root@VM-12-2-centos" D [21/May/2022:09:00:05 +0800] [Job 4] envp[10]="SOFTWARE=CUPS/2.4.1" D [21/May/2022:09:00:05 +0800] [Job 4] envp[11]="TMPDIR=/var/spool/cups/tmp" D [21/May/2022:09:00:05 +0800] [Job 4] envp[12]="USER=root" D [21/May/2022:09:00:05 +0800] [Job 4] envp[13]="CUPS_MAX_MESSAGE=2047" D [21/May/2022:09:00:05 +0800] [Job 4] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock" D [21/May/2022:09:00:05 +0800] [Job 4] envp[15]="CUPS_ENCRYPTION=IfRequested" D [21/May/2022:09:00:05 +0800] [Job 4] envp[16]="IPP_PORT=6310" D [21/May/2022:09:00:05 +0800] [Job 4] envp[17]="CHARSET=utf-8" D [21/May/2022:09:00:05 +0800] [Job 4] envp[18]="LANG=zh_CN.UTF-8" D [21/May/2022:09:00:05 +0800] [Job 4] envp[19]="PPD=/etc/cups/ppd/t20210525514lenovolj2400.ppd" D [21/May/2022:09:00:05 +0800] [Job 4] envp[20]="CONTENT_TYPE=application/vnd.cups-pdf-banner" D [21/May/2022:09:00:05 +0800] [Job 4] envp[21]="DEVICE_URI=ipp://t20210525514lenovolj2400.xxx.test.com:6318/xxx/xx" D [21/May/2022:09:00:05 +0800] [Job 4] envp[22]="PRINTER_INFO=t20210525514lenovolj2400" D [21/May/2022:09:00:05 +0800] [Job 4] envp[23]="PRINTER_LOCATION=" D [21/May/2022:09:00:05 +0800] [Job 4] envp[24]="PRINTER=t20210525514lenovolj2400" D [21/May/2022:09:00:05 +0800] [Job 4] envp[25]="PRINTER_STATE_REASONS=none" D [21/May/2022:09:00:05 +0800] [Job 4] envp[26]="CUPS_FILETYPE=document" D [21/May/2022:09:00:05 +0800] [Job 4] envp[27]="FINAL_CONTENT_TYPE=application/vnd.cups-pdf" D [21/May/2022:09:00:05 +0800] [Job 4] envp[28]="AUTH_I****" I [21/May/2022:09:00:05 +0800] [Job 4] Started filter /usr/lib/cups/filter/bannertopdf (PID 2767) I [21/May/2022:09:00:05 +0800] [Job 4] Started filter /usr/lib/cups/filter/pdftopdf (PID 2768) I [21/May/2022:09:00:05 +0800] [Job 4] Started filter /usr/lib/cups/filter/foomatic-rip (PID 2769) I [21/May/2022:09:00:05 +0800] [Job 4] Started backend /usr/lib/cups/backend/ipp (PID 2770) D [21/May/2022:09:00:05 +0800] cupsdMarkDirty(----S) D [21/May/2022:09:00:05 +0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files" D [21/May/2022:09:00:05 +0800] [Client 18] Returning IPP successful-ok for Print-Job (ipp://localhost:6310/printers/t20210525514lenovolj2400) from localhost. D [21/May/2022:09:00:05 +0800] [Client 18] Content-Length: 191 D [21/May/2022:09:00:05 +0800] [Client 18] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:09:00:05 +0800] [Client 18] con->http=0x55fe0d09a7e0 D [21/May/2022:09:00:05 +0800] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=191, response=0x55fe0d0d3810(IPP_STATE_IDLE), pipe_pid=0, file=-1 D [21/May/2022:09:00:05 +0800] [Client 18] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:09:00:05 +0800] [Client 18] bytes=0, http_state=0, data_remaining=191 D [21/May/2022:09:00:05 +0800] [Client 18] Flushing write buffer. D [21/May/2022:09:00:05 +0800] [Client 18] New state is HTTP_STATE_WAITING D [21/May/2022:09:00:05 +0800] [Client 18] Waiting for request. D [21/May/2022:09:00:05 +0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/May/2022:09:00:05 +0800] [Notifier] state=3 D [21/May/2022:09:00:05 +0800] [Notifier] JobCreated D [21/May/2022:09:00:05 +0800] [Notifier] state=3 D [21/May/2022:09:00:05 +0800] [Notifier] PrinterStateChanged D [21/May/2022:09:00:05 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:09:00:05 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:09:00:05 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:09:00:05 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:09:00:05 +0800] [CGI] cgiSetArray: job_uri[0]=\"/jobs/4\" D [21/May/2022:09:00:05 +0800] [CGI] cgiSetArray: job_id[0]=\"4\" D [21/May/2022:09:00:05 +0800] [CGI] cgiSetArray: job_state[0]=\"3\" D [21/May/2022:09:00:05 +0800] [CGI] cgiSetArray: job_state_message[0]=\"\" D [21/May/2022:09:00:05 +0800] [CGI] cgiSetArray: job_state_reasons[0]=\"none\" D [21/May/2022:09:00:05 +0800] [Notifier] state=3 D [21/May/2022:09:00:05 +0800] [Notifier] JobState D [21/May/2022:09:00:05 +0800] [CGI] cgiSetVariable: refresh_page=\"2;URL=/printers/t20210525514lenovolj2400\" D [21/May/2022:09:00:05 +0800] [CGI] cgiSetVariable: TITLE=\"打印测试页\" D [21/May/2022:09:00:05 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:09:00:05 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:09:00:05 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:05 +0800] [CGI] cgiSetVariable: PRINTER_NAME=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:05 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:05 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=2766, file=24 D [21/May/2022:09:00:05 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:05 +0800] [Client 8] Script header: Content-Type: text/html;charset=utf-8 D [21/May/2022:09:00:05 +0800] [Client 8] Script header: D [21/May/2022:09:00:05 +0800] [Client 8] Sending status 200 for CGI. D [21/May/2022:09:00:05 +0800] [Client 8] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [21/May/2022:09:00:05 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:05 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2766, file=24 D [21/May/2022:09:00:05 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:05 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:05 +0800] [Client 18] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [21/May/2022:09:00:05 +0800] [Client 18] Closing connection. D [21/May/2022:09:00:05 +0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/May/2022:09:00:05 +0800] PID 2766 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [21/May/2022:09:00:05 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:05 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2766, file=24 D [21/May/2022:09:00:05 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:05 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:05 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2766, file=24 D [21/May/2022:09:00:05 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:05 +0800] [Job 4] Calling FindDeviceById(cups-t20210525514lenovolj2400) D [21/May/2022:09:00:05 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:05 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:05 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2766, file=24 D [21/May/2022:09:00:05 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:05 +0800] [Client 8] Sending 0-length chunk. D [21/May/2022:09:00:05 +0800] [Client 8] Flushing write buffer. D [21/May/2022:09:00:05 +0800] [Client 8] New state is HTTP_STATE_WAITING D [21/May/2022:09:00:05 +0800] [Client 8] Waiting for request. D [21/May/2022:09:00:05 +0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/May/2022:09:00:05 +0800] [Job 4] Sending stdin for job... D [21/May/2022:09:00:05 +0800] [Job 4] STATE: +connecting-to-device D [21/May/2022:09:00:05 +0800] cupsdMarkDirty(---J-) D [21/May/2022:09:00:05 +0800] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files" D [21/May/2022:09:00:05 +0800] cupsdMarkDirty(----S) D [21/May/2022:09:00:05 +0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/May/2022:09:00:05 +0800] [Job 4] Looking up \"t20210525514lenovolj2400.xxx.test.com\"... D [21/May/2022:09:00:05 +0800] [Notifier] state=3 D [21/May/2022:09:00:05 +0800] [Notifier] PrinterStateChanged D [21/May/2022:09:00:05 +0800] [Job 4] Failed to send: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files D [21/May/2022:09:00:05 +0800] [Job 4] Failed to get find device cups-t20210525514lenovolj2400 D [21/May/2022:09:00:05 +0800] [Job 4] \'CM Color Calibration\' Mode in SPOOLER-LESS: Off D [21/May/2022:09:00:05 +0800] [Job 4] Getting input from file D [21/May/2022:09:00:05 +0800] [Job 4] foomatic-rip version 1.28.15 running... D [21/May/2022:09:00:05 +0800] [Job 4] Parsing PPD file ... D [21/May/2022:09:00:05 +0800] [Job 4] Added option ColorSpace D [21/May/2022:09:00:05 +0800] [Job 4] Added option PageSize D [21/May/2022:09:00:05 +0800] [Job 4] Added option ImageableArea D [21/May/2022:09:00:05 +0800] [Job 4] Added option PaperDimension D [21/May/2022:09:00:05 +0800] [Job 4] Added option EconoMode D [21/May/2022:09:00:05 +0800] [Job 4] Added option InputSlot D [21/May/2022:09:00:05 +0800] [Job 4] Added option Resolution D [21/May/2022:09:00:05 +0800] [Job 4] Added option MediaType D [21/May/2022:09:00:05 +0800] [Job 4] Added option Font D [21/May/2022:09:00:05 +0800] [Job 4] Parameter Summary D [21/May/2022:09:00:05 +0800] [Job 4] ----------------- D [21/May/2022:09:00:05 +0800] [Job 4] Spooler: cups D [21/May/2022:09:00:05 +0800] [Job 4] Printer: t20210525514lenovolj2400 D [21/May/2022:09:00:05 +0800] [Job 4] Shell: /bin/bash D [21/May/2022:09:00:05 +0800] [Job 4] PPD file: /etc/cups/ppd/t20210525514lenovolj2400.ppd D [21/May/2022:09:00:05 +0800] [Job 4] ATTR file: D [21/May/2022:09:00:05 +0800] [Job 4] Printer model: Brother HL-2240 Foomatic/hl1250 (recommended) D [21/May/2022:09:00:05 +0800] [Job 4] Job title: Test Page D [21/May/2022:09:00:05 +0800] [Job 4] File(s) to be printed: D [21/May/2022:09:00:05 +0800] [Job 4] D [21/May/2022:09:00:05 +0800] [Job 4] Ghostscript extra search path (\'GS_LIB\'): /usr/share/cups/fonts D [21/May/2022:09:00:05 +0800] [Job 4] Printing system options: D [21/May/2022:09:00:05 +0800] [Job 4] Pondering option \'job-uuid=urn:uuid:d58dc47d-b1da-3d92-4059-1b9c706f80a7\' D [21/May/2022:09:00:05 +0800] [Job 4] Unknown option job-uuid=urn:uuid:d58dc47d-b1da-3d92-4059-1b9c706f80a7. D [21/May/2022:09:00:05 +0800] [Job 4] Pondering option \'job-originating-host-name=localhost\' D [21/May/2022:09:00:05 +0800] [Job 4] Unknown option job-originating-host-name=localhost. D [21/May/2022:09:00:05 +0800] [Job 4] Pondering option \'date-time-at-creation=\' D [21/May/2022:09:00:05 +0800] [Job 4] Unknown option date-time-at-creation=. D [21/May/2022:09:00:05 +0800] [Job 4] Pondering option \'date-time-at-processing=\' D [21/May/2022:09:00:05 +0800] [Job 4] Unknown option date-time-at-processing=. D [21/May/2022:09:00:05 +0800] [Job 4] Pondering option \'time-at-creation=1653094805\' D [21/May/2022:09:00:05 +0800] [Job 4] Unknown option time-at-creation=1653094805. D [21/May/2022:09:00:05 +0800] [Job 4] Pondering option \'time-at-processing=1653094805\' D [21/May/2022:09:00:05 +0800] [Job 4] Unknown option time-at-processing=1653094805. D [21/May/2022:09:00:05 +0800] [Job 4] CM Color Calibration Mode in CUPS: Off D [21/May/2022:09:00:05 +0800] [Job 4] Options from the PPD file: D [21/May/2022:09:00:05 +0800] [Job 4] ================================================ D [21/May/2022:09:00:05 +0800] [Job 4] File: D [21/May/2022:09:00:05 +0800] [Job 4] ================================================ D [21/May/2022:09:00:05 +0800] [Job 4] pdftopdf: Last filter determined by the PPD: foomatic-rip; FINAL_CONTENT_TYPE: application/vnd.cups-pdf => pdftopdf will log pages in page_log. D [21/May/2022:09:00:05 +0800] [Job 4] PDF template file doesn\'t have form. It\'s okay. D [21/May/2022:09:00:05 +0800] [Job 4] PID 2767 (/usr/lib/cups/filter/bannertopdf) exited with no errors. D [21/May/2022:09:00:05 +0800] [Job 4] PDF interactive form and annotation flattening done via QPDF D [21/May/2022:09:00:05 +0800] [Job 4] pdftopdf: \"print-scaling\" IPP attribute: auto D [21/May/2022:09:00:05 +0800] [Job 4] pdftopdf: Print scaling mode: Do not scale, center, crop if needed D [21/May/2022:09:00:05 +0800] [Job 4] After Cropping: 595.000000 842.000000 595.000000 842.000000 D [21/May/2022:09:00:05 +0800] [Job 4] PAGE: 1 1 D [21/May/2022:09:00:05 +0800] cupsdMarkDirty(----S) D [21/May/2022:09:00:05 +0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/May/2022:09:00:05 +0800] cupsdMarkDirty(---J-) D [21/May/2022:09:00:05 +0800] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [21/May/2022:09:00:05 +0800] [Notifier] state=3 D [21/May/2022:09:00:05 +0800] [Notifier] JobProgress D [21/May/2022:09:00:05 +0800] [Job 4] Filetype: PDF D [21/May/2022:09:00:05 +0800] [Job 4] Storing temporary files in /var/spool/cups/tmp D [21/May/2022:09:00:05 +0800] [Job 4] PID 2768 (/usr/lib/cups/filter/pdftopdf) exited with no errors. D [21/May/2022:09:00:05 +0800] [Job 4] sh: gs: 未找到命令 D [21/May/2022:09:00:05 +0800] [Job 4] Process is dying with \"Unable to determine number of pages, page count: -1 D [21/May/2022:09:00:05 +0800] [Job 4] \", exit stat 3 D [21/May/2022:09:00:05 +0800] [Job 4] Cleaning up... D [21/May/2022:09:00:05 +0800] [Job 4] PID 2769 (/usr/lib/cups/filter/foomatic-rip) stopped with status 3. D [21/May/2022:09:00:05 +0800] [Job 4] STATE: -connecting-to-device D [21/May/2022:09:00:05 +0800] cupsdMarkDirty(---J-) D [21/May/2022:09:00:05 +0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files" D [21/May/2022:09:00:05 +0800] [Job 4] t20210525514lenovolj2400.xxx.test.com=101.122.103.231 D [21/May/2022:09:00:05 +0800] cupsdMarkDirty(----S) D [21/May/2022:09:00:05 +0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/May/2022:09:00:05 +0800] [Notifier] state=3 D [21/May/2022:09:00:05 +0800] [Notifier] PrinterStateChanged I [21/May/2022:09:00:06 +0800] Expiring subscriptions... D [21/May/2022:09:00:07 +0800] [Client 8] GET /printers/t20210525514lenovolj2400 HTTP/1.1 D [21/May/2022:09:00:07 +0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [21/May/2022:09:00:07 +0800] [Client 8] Read: status=200, state=3 D [21/May/2022:09:00:07 +0800] [Client 8] No authentication data provided. D [21/May/2022:09:00:07 +0800] [Client 8] Processing GET /printers/t20210525514lenovolj2400 D [21/May/2022:09:00:07 +0800] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [21/May/2022:09:00:07 +0800] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [21/May/2022:09:00:07 +0800] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [21/May/2022:09:00:07 +0800] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/doc/cups" D [21/May/2022:09:00:07 +0800] [CGI] envp[3] = "CUPS_REQUESTROOT=/var/spool/cups" D [21/May/2022:09:00:07 +0800] [CGI] envp[4] = "CUPS_SERVERBIN=/usr/lib/cups" D [21/May/2022:09:00:07 +0800] [CGI] envp[5] = "CUPS_SERVERROOT=/etc/cups" D [21/May/2022:09:00:07 +0800] [CGI] envp[6] = "CUPS_STATEDIR=/var/run/cups" D [21/May/2022:09:00:07 +0800] [CGI] envp[7] = "HOME=/var/spool/cups/tmp" D [21/May/2022:09:00:07 +0800] [CGI] envp[8] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [21/May/2022:09:00:07 +0800] [CGI] envp[9] = "SERVER_ADMIN=root@VM-12-2-centos" D [21/May/2022:09:00:07 +0800] [CGI] envp[10] = "SOFTWARE=CUPS/2.4.1" D [21/May/2022:09:00:07 +0800] [CGI] envp[11] = "TMPDIR=/var/spool/cups/tmp" D [21/May/2022:09:00:07 +0800] [CGI] envp[12] = "USER=root" D [21/May/2022:09:00:07 +0800] [CGI] envp[13] = "CUPS_MAX_MESSAGE=2047" D [21/May/2022:09:00:07 +0800] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [21/May/2022:09:00:07 +0800] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [21/May/2022:09:00:07 +0800] [CGI] envp[16] = "IPP_PORT=6310" D [21/May/2022:09:00:07 +0800] [CGI] envp[17] = "LANG=zh_CN.UTF8" D [21/May/2022:09:00:07 +0800] [CGI] envp[18] = "REDIRECT_STATUS=1" D [21/May/2022:09:00:07 +0800] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1" D [21/May/2022:09:00:07 +0800] [CGI] envp[20] = "SERVER_NAME=xxx.test.com" D [21/May/2022:09:00:07 +0800] [CGI] envp[21] = "SERVER_PORT=6310" D [21/May/2022:09:00:07 +0800] [CGI] envp[22] = "REMOTE_ADDR=153.34.163.25" D [21/May/2022:09:00:07 +0800] [CGI] envp[23] = "REMOTE_HOST=153.34.163.25" D [21/May/2022:09:00:07 +0800] [CGI] envp[24] = "SCRIPT_NAME=/printers/t20210525514lenovolj2400" D [21/May/2022:09:00:07 +0800] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/doc/cups/printers/t20210525514lenovolj2400" D [21/May/2022:09:00:07 +0800] [CGI] envp[26] = "PATH_INFO=/t20210525514lenovolj2400" D [21/May/2022:09:00:07 +0800] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1" D [21/May/2022:09:00:07 +0800] [CGI] envp[28] = "HTTP_COOKIE=org.cups.sid=a9bd340c037825aa984a32b3397ddd91" D [21/May/2022:09:00:07 +0800] [CGI] envp[29] = "HTTP_USER_AGENT=Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/101.0.4951.64 Safari/537.36 Edg/101.0.1210.47" D [21/May/2022:09:00:07 +0800] [CGI] envp[30] = "HTTP_REFERER=http://xxx.test.com:6310/printers/t20210525514lenovolj2400" D [21/May/2022:09:00:07 +0800] [CGI] envp[31] = "REQUEST_METHOD=GET" D [21/May/2022:09:00:07 +0800] [CGI] envp[32] = "QUERY_STRING=" D [21/May/2022:09:00:07 +0800] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 2773) I [21/May/2022:09:00:07 +0800] [Client 8] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=2773, file=22) D [21/May/2022:09:00:07 +0800] [Client 8] Waiting for CGI data. I [21/May/2022:09:00:07 +0800] Expiring subscriptions... D [21/May/2022:09:00:07 +0800] [CGI] org.cups.sid cookie is \"a9bd340c037825aa984a32b3397ddd91\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetVariable: SECTION=\"printers\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetVariable: PRINTER_NAME=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:07 +0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/May/2022:09:00:07 +0800] [Client 19] Server address is "/var/run/cups/cups.sock". D [21/May/2022:09:00:07 +0800] [Client 19] Accepted from localhost (Domain) D [21/May/2022:09:00:07 +0800] [Client 19] Waiting for request. D [21/May/2022:09:00:07 +0800] [Client 19] POST / HTTP/1.1 D [21/May/2022:09:00:07 +0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/May/2022:09:00:07 +0800] [Client 19] Read: status=200, state=6 D [21/May/2022:09:00:07 +0800] [Client 19] No authentication data provided. D [21/May/2022:09:00:07 +0800] [Client 19] 2.0 CUPS-Get-Default 1 D [21/May/2022:09:00:07 +0800] CUPS-Get-Default D [21/May/2022:09:00:07 +0800] CUPS-Get-Default client-error-not-found: 无默认打印机。 D [21/May/2022:09:00:07 +0800] [Client 19] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost. D [21/May/2022:09:00:07 +0800] [Client 19] Content-Length: 115 D [21/May/2022:09:00:07 +0800] [Client 19] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:09:00:07 +0800] [Client 19] con->http=0x55fe0d0d6220 D [21/May/2022:09:00:07 +0800] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=115, response=0x55fe0d09bff0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:09:00:07 +0800] [Client 19] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:09:00:07 +0800] [Client 19] bytes=0, http_state=0, data_remaining=115 D [21/May/2022:09:00:07 +0800] [Client 19] Flushing write buffer. D [21/May/2022:09:00:07 +0800] [Client 19] New state is HTTP_STATE_WAITING D [21/May/2022:09:00:07 +0800] [Client 19] Waiting for request. D [21/May/2022:09:00:07 +0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/May/2022:09:00:07 +0800] [CGI] show_printer(http=0x56066be2be20, printer=\"t20210525514lenovolj2400\") D [21/May/2022:09:00:07 +0800] [Client 19] POST / HTTP/1.1 D [21/May/2022:09:00:07 +0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/May/2022:09:00:07 +0800] [Client 19] Read: status=200, state=6 D [21/May/2022:09:00:07 +0800] [Client 19] No authentication data provided. D [21/May/2022:09:00:07 +0800] [Client 19] 2.0 Get-Printer-Attributes 2 D [21/May/2022:09:00:07 +0800] Get-Printer-Attributes ipp://localhost/printers/t20210525514lenovolj2400 D [21/May/2022:09:00:07 +0800] [Client 19] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/t20210525514lenovolj2400) from localhost. D [21/May/2022:09:00:07 +0800] [Client 19] Content-Length: 729 D [21/May/2022:09:00:07 +0800] [Client 19] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:09:00:07 +0800] [Client 19] con->http=0x55fe0d0d6220 D [21/May/2022:09:00:07 +0800] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=729, response=0x55fe0d09bf60(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:09:00:07 +0800] [Client 19] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:09:00:07 +0800] [Client 19] bytes=0, http_state=0, data_remaining=729 D [21/May/2022:09:00:07 +0800] [Client 19] Flushing write buffer. D [21/May/2022:09:00:07 +0800] [Client 19] New state is HTTP_STATE_WAITING D [21/May/2022:09:00:07 +0800] [Client 19] Waiting for request. D [21/May/2022:09:00:07 +0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: printer_is_shared[0]=\"1\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: printer_state[0]=\"4\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: printer_name[0]=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: printer_location[0]=\"\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: printer_info[0]=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: device_uri[0]=\"ipp://t20210525514lenovolj2400.xxx.test.com:6318/xxx/xx\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: color_supported[0]=\"0\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: printer_make_and_model[0]=\"Brother HL-2240 Foomatic/hl1250 (recommended)\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: media_default[0]=\"iso_a4_210x297mm\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: sides_supported[0]=\"one-sided\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: sides_default[0]=\"one-sided\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: printer_commands[0]=\"AutoConfigure, Clean, PrintSelfTestPage\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"1\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetVariable: refresh_page=\"10;URL=/printers/t20210525514lenovolj2400\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetVariable: TITLE=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:09:00:07 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:07 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:07 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=2773, file=22 D [21/May/2022:09:00:07 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:07 +0800] [Client 8] Script header: Content-Type: text/html;charset=utf-8 D [21/May/2022:09:00:07 +0800] [Client 8] Script header: D [21/May/2022:09:00:07 +0800] [Client 8] Sending status 200 for CGI. D [21/May/2022:09:00:07 +0800] [Client 8] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [21/May/2022:09:00:07 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:07 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2773, file=22 D [21/May/2022:09:00:07 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:07 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:07 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:07 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2773, file=22 D [21/May/2022:09:00:07 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:07 +0800] [CGI] Regular expression \".Clean.\" D [21/May/2022:09:00:07 +0800] [CGI] matches[0].rm_so=0 D [21/May/2022:09:00:07 +0800] [CGI] matches[1].rm_so=-1 D [21/May/2022:09:00:07 +0800] [CGI] Regular expression \".PrintSelfTestPage.\" D [21/May/2022:09:00:07 +0800] [CGI] matches[0].rm_so=0 D [21/May/2022:09:00:07 +0800] [CGI] matches[1].rm_so=-1 D [21/May/2022:09:00:07 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:07 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2773, file=22 D [21/May/2022:09:00:07 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:07 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:07 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:07 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2773, file=22 D [21/May/2022:09:00:07 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:07 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:07 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2773, file=22 D [21/May/2022:09:00:07 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:07 +0800] [Client 19] POST / HTTP/1.1 D [21/May/2022:09:00:07 +0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/May/2022:09:00:07 +0800] [Client 19] Read: status=200, state=6 D [21/May/2022:09:00:07 +0800] [Client 19] No authentication data provided. D [21/May/2022:09:00:07 +0800] [Client 19] 2.0 Get-Jobs 3 D [21/May/2022:09:00:07 +0800] Get-Jobs ipp://localhost:6310/printers/t20210525514lenovolj2400 D [21/May/2022:09:00:07 +0800] [Client 19] Returning IPP successful-ok for Get-Jobs (ipp://localhost:6310/printers/t20210525514lenovolj2400) from localhost. D [21/May/2022:09:00:07 +0800] [Client 19] Content-Length: 437 D [21/May/2022:09:00:07 +0800] [Client 19] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:09:00:07 +0800] [Client 19] con->http=0x55fe0d0d6220 D [21/May/2022:09:00:07 +0800] [Client 19] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=437, response=0x55fe0d09bff0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:09:00:07 +0800] [Client 19] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:09:00:07 +0800] [Client 19] bytes=0, http_state=0, data_remaining=437 D [21/May/2022:09:00:07 +0800] [Client 19] Flushing write buffer. D [21/May/2022:09:00:07 +0800] [Client 19] New state is HTTP_STATE_WAITING D [21/May/2022:09:00:07 +0800] [Client 19] Waiting for request. D [21/May/2022:09:00:07 +0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/May/2022:09:00:07 +0800] [CGI] cgiClearVariables called. D [21/May/2022:09:00:07 +0800] [CGI] cgiSetVariable: SECTION=\"printers\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetVariable: TOTAL=\"1\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: job_printer_name[0]=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: job_printer_uri[0]=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: job_name[0]=\"Test Page\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: job_originating_user_name[0]=\"anonymous\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: time_at_completed[0]=\"novalue\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: time_at_creation[0]=\"2022年05月21日 星期六 09时00分05秒\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: time_at_processing[0]=\"2022年05月21日 星期六 09时00分05秒\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: job_id[0]=\"4\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: job_state[0]=\"5\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: job_impressions_completed[0]=\"1\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: job_k_octets[0]=\"1\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: job_hold_until[0]=\"no-hold\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetArray: job_printer_state_message[0]=\"Filter failed\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetVariable: PRINTER_NAME=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetVariable: THISURL=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:09:00:07 +0800] [CGI] cgiSetVariable: SEARCH_DEST=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:07 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:07 +0800] [Client 19] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [21/May/2022:09:00:07 +0800] [Client 19] Closing connection. D [21/May/2022:09:00:07 +0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [21/May/2022:09:00:07 +0800] PID 2773 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [21/May/2022:09:00:07 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:07 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2773, file=22 D [21/May/2022:09:00:07 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:07 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:07 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2773, file=22 D [21/May/2022:09:00:07 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:07 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:07 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:07 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2773, file=22 D [21/May/2022:09:00:07 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:07 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:07 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2773, file=22 D [21/May/2022:09:00:07 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:07 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:07 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:07 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2773, file=22 D [21/May/2022:09:00:07 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:07 +0800] [Client 8] Sending 0-length chunk. D [21/May/2022:09:00:07 +0800] [Client 8] Flushing write buffer. D [21/May/2022:09:00:07 +0800] [Client 8] New state is HTTP_STATE_WAITING D [21/May/2022:09:00:07 +0800] [Client 8] Waiting for request. D [21/May/2022:09:00:07 +0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" I [21/May/2022:09:00:08 +0800] Expiring subscriptions... D [21/May/2022:09:00:09 +0800] [Job 4] prtGeneralCurrentLocalization type is 0, expected 2! D [21/May/2022:09:00:09 +0800] [Job 4] backendWaitLoop(snmp_fd=5, addr=0x55cbfd4f7088, side_cb=0x55cbfb493bb1) I [21/May/2022:09:00:09 +0800] Expiring subscriptions... D [21/May/2022:09:00:09 +0800] [Job 4] PID 2770 (/usr/lib/cups/backend/ipp) exited with no errors. D [21/May/2022:09:00:09 +0800] cupsdMarkDirty(----S) D [21/May/2022:09:00:09 +0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" E [21/May/2022:09:00:09 +0800] [Job 4] Job stopped due to filter errors; please consult the /var/log/cups/error_log file for details. D [21/May/2022:09:00:09 +0800] cupsdMarkDirty(---J-) D [21/May/2022:09:00:09 +0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/May/2022:09:00:09 +0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/May/2022:09:00:09 +0800] cupsdMarkDirty(----S) D [21/May/2022:09:00:09 +0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [21/May/2022:09:00:09 +0800] [Job 4] The following messages were recorded from 09:00:07 AM to 09:00:07 AM D [21/May/2022:09:00:09 +0800] [Job 4] hrDeviceDesc=\"Unknown\" D [21/May/2022:09:00:09 +0800] [Job 4] End of messages D [21/May/2022:09:00:09 +0800] [Job 4] printer-state=3(idle) D [21/May/2022:09:00:09 +0800] [Job 4] printer-state-message="Filter failed" D [21/May/2022:09:00:09 +0800] [Job 4] printer-state-reasons=none D [21/May/2022:09:00:09 +0800] [Notifier] state=3 D [21/May/2022:09:00:09 +0800] [Notifier] JobState D [21/May/2022:09:00:09 +0800] [Notifier] state=3 D [21/May/2022:09:00:09 +0800] [Notifier] PrinterStateChanged I [21/May/2022:09:00:10 +0800] Expiring subscriptions... D [21/May/2022:09:00:10 +0800] [Job 4] Unloading... I [21/May/2022:09:00:16 +0800] Generating printcap /etc/printcap... I [21/May/2022:09:00:16 +0800] Saving job.cache... I [21/May/2022:09:00:16 +0800] Saving subscriptions.conf... D [21/May/2022:09:00:16 +0800] cupsdSetBusyState: newbusy="Not busy", busy="Printing jobs and dirty files" I [21/May/2022:09:00:16 +0800] Expiring subscriptions... D [21/May/2022:09:00:17 +0800] [Client 8] GET /printers/t20210525514lenovolj2400 HTTP/1.1 D [21/May/2022:09:00:17 +0800] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [21/May/2022:09:00:17 +0800] [Client 8] Read: status=200, state=3 D [21/May/2022:09:00:17 +0800] [Client 8] No authentication data provided. D [21/May/2022:09:00:17 +0800] [Client 8] Processing GET /printers/t20210525514lenovolj2400 D [21/May/2022:09:00:17 +0800] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [21/May/2022:09:00:17 +0800] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [21/May/2022:09:00:17 +0800] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [21/May/2022:09:00:17 +0800] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/doc/cups" D [21/May/2022:09:00:17 +0800] [CGI] envp[3] = "CUPS_REQUESTROOT=/var/spool/cups" D [21/May/2022:09:00:17 +0800] [CGI] envp[4] = "CUPS_SERVERBIN=/usr/lib/cups" D [21/May/2022:09:00:17 +0800] [CGI] envp[5] = "CUPS_SERVERROOT=/etc/cups" D [21/May/2022:09:00:17 +0800] [CGI] envp[6] = "CUPS_STATEDIR=/var/run/cups" D [21/May/2022:09:00:17 +0800] [CGI] envp[7] = "HOME=/var/spool/cups/tmp" D [21/May/2022:09:00:17 +0800] [CGI] envp[8] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [21/May/2022:09:00:17 +0800] [CGI] envp[9] = "SERVER_ADMIN=root@VM-12-2-centos" D [21/May/2022:09:00:17 +0800] [CGI] envp[10] = "SOFTWARE=CUPS/2.4.1" D [21/May/2022:09:00:17 +0800] [CGI] envp[11] = "TMPDIR=/var/spool/cups/tmp" D [21/May/2022:09:00:17 +0800] [CGI] envp[12] = "USER=root" D [21/May/2022:09:00:17 +0800] [CGI] envp[13] = "CUPS_MAX_MESSAGE=2047" D [21/May/2022:09:00:17 +0800] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [21/May/2022:09:00:17 +0800] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [21/May/2022:09:00:17 +0800] [CGI] envp[16] = "IPP_PORT=6310" D [21/May/2022:09:00:17 +0800] [CGI] envp[17] = "LANG=zh_CN.UTF8" D [21/May/2022:09:00:17 +0800] [CGI] envp[18] = "REDIRECT_STATUS=1" D [21/May/2022:09:00:17 +0800] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1" D [21/May/2022:09:00:17 +0800] [CGI] envp[20] = "SERVER_NAME=xxx.test.com" D [21/May/2022:09:00:17 +0800] [CGI] envp[21] = "SERVER_PORT=6310" D [21/May/2022:09:00:17 +0800] [CGI] envp[22] = "REMOTE_ADDR=153.34.163.25" D [21/May/2022:09:00:17 +0800] [CGI] envp[23] = "REMOTE_HOST=153.34.163.25" D [21/May/2022:09:00:17 +0800] [CGI] envp[24] = "SCRIPT_NAME=/printers/t20210525514lenovolj2400" D [21/May/2022:09:00:17 +0800] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/doc/cups/printers/t20210525514lenovolj2400" D [21/May/2022:09:00:17 +0800] [CGI] envp[26] = "PATH_INFO=/t20210525514lenovolj2400" D [21/May/2022:09:00:17 +0800] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1" D [21/May/2022:09:00:17 +0800] [CGI] envp[28] = "HTTP_COOKIE=org.cups.sid=a9bd340c037825aa984a32b3397ddd91" D [21/May/2022:09:00:17 +0800] [CGI] envp[29] = "HTTP_USER_AGENT=Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/101.0.4951.64 Safari/537.36 Edg/101.0.1210.47" D [21/May/2022:09:00:17 +0800] [CGI] envp[30] = "HTTP_REFERER=http://xxx.test.com:6310/printers/t20210525514lenovolj2400" D [21/May/2022:09:00:17 +0800] [CGI] envp[31] = "REQUEST_METHOD=GET" D [21/May/2022:09:00:17 +0800] [CGI] envp[32] = "QUERY_STRING=" D [21/May/2022:09:00:17 +0800] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 2804) I [21/May/2022:09:00:17 +0800] [Client 8] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=2804, file=22) D [21/May/2022:09:00:17 +0800] [Client 8] Waiting for CGI data. I [21/May/2022:09:00:17 +0800] Expiring subscriptions... D [21/May/2022:09:00:17 +0800] [CGI] org.cups.sid cookie is \"a9bd340c037825aa984a32b3397ddd91\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetVariable: SECTION=\"printers\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetVariable: PRINTER_NAME=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:17 +0800] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [21/May/2022:09:00:17 +0800] [Client 20] Server address is "/var/run/cups/cups.sock". D [21/May/2022:09:00:17 +0800] [Client 20] Accepted from localhost (Domain) D [21/May/2022:09:00:17 +0800] [Client 20] Waiting for request. D [21/May/2022:09:00:17 +0800] [Client 20] POST / HTTP/1.1 D [21/May/2022:09:00:17 +0800] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [21/May/2022:09:00:17 +0800] [Client 20] Read: status=200, state=6 D [21/May/2022:09:00:17 +0800] [Client 20] No authentication data provided. D [21/May/2022:09:00:17 +0800] [Client 20] 2.0 CUPS-Get-Default 1 D [21/May/2022:09:00:17 +0800] CUPS-Get-Default D [21/May/2022:09:00:17 +0800] CUPS-Get-Default client-error-not-found: 无默认打印机。 D [21/May/2022:09:00:17 +0800] [Client 20] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost. D [21/May/2022:09:00:17 +0800] [Client 20] Content-Length: 115 D [21/May/2022:09:00:17 +0800] [Client 20] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:09:00:17 +0800] [Client 20] con->http=0x55fe0d0d6220 D [21/May/2022:09:00:17 +0800] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=115, response=0x55fe0d09de70(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:09:00:17 +0800] [Client 20] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:09:00:17 +0800] [Client 20] bytes=0, http_state=0, data_remaining=115 D [21/May/2022:09:00:17 +0800] [Client 20] Flushing write buffer. D [21/May/2022:09:00:17 +0800] [Client 20] New state is HTTP_STATE_WAITING D [21/May/2022:09:00:17 +0800] [Client 20] Waiting for request. D [21/May/2022:09:00:17 +0800] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [21/May/2022:09:00:17 +0800] [CGI] show_printer(http=0x557d1bbeae20, printer=\"t20210525514lenovolj2400\") D [21/May/2022:09:00:17 +0800] [Client 20] POST / HTTP/1.1 D [21/May/2022:09:00:17 +0800] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [21/May/2022:09:00:17 +0800] [Client 20] Read: status=200, state=6 D [21/May/2022:09:00:17 +0800] [Client 20] No authentication data provided. D [21/May/2022:09:00:17 +0800] [Client 20] 2.0 Get-Printer-Attributes 2 D [21/May/2022:09:00:17 +0800] Get-Printer-Attributes ipp://localhost/printers/t20210525514lenovolj2400 D [21/May/2022:09:00:17 +0800] [Client 20] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/t20210525514lenovolj2400) from localhost. D [21/May/2022:09:00:17 +0800] [Client 20] Content-Length: 729 D [21/May/2022:09:00:17 +0800] [Client 20] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:09:00:17 +0800] [Client 20] con->http=0x55fe0d0d6220 D [21/May/2022:09:00:17 +0800] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=729, response=0x55fe0d09cab0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:09:00:17 +0800] [Client 20] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:09:00:17 +0800] [Client 20] bytes=0, http_state=0, data_remaining=729 D [21/May/2022:09:00:17 +0800] [Client 20] Flushing write buffer. D [21/May/2022:09:00:17 +0800] [Client 20] New state is HTTP_STATE_WAITING D [21/May/2022:09:00:17 +0800] [Client 20] Waiting for request. D [21/May/2022:09:00:17 +0800] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: printer_is_shared[0]=\"1\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: printer_state[0]=\"3\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: printer_name[0]=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: printer_location[0]=\"\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: printer_info[0]=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: device_uri[0]=\"ipp://t20210525514lenovolj2400.xxx.test.com:6318/xxx/xx\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: color_supported[0]=\"0\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: printer_make_and_model[0]=\"Brother HL-2240 Foomatic/hl1250 (recommended)\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: media_default[0]=\"iso_a4_210x297mm\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: sides_supported[0]=\"one-sided\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: sides_default[0]=\"one-sided\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: printer_commands[0]=\"AutoConfigure, Clean, PrintSelfTestPage\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"1\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetVariable: TITLE=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:09:00:17 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:17 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:17 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=2804, file=22 D [21/May/2022:09:00:17 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:17 +0800] [Client 8] Script header: Content-Type: text/html;charset=utf-8 D [21/May/2022:09:00:17 +0800] [Client 8] Script header: D [21/May/2022:09:00:17 +0800] [Client 8] Sending status 200 for CGI. D [21/May/2022:09:00:17 +0800] [Client 8] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [21/May/2022:09:00:17 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:17 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2804, file=22 D [21/May/2022:09:00:17 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:17 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:17 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:17 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2804, file=22 D [21/May/2022:09:00:17 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:17 +0800] [CGI] Regular expression \".Clean.\" D [21/May/2022:09:00:17 +0800] [CGI] matches[0].rm_so=0 D [21/May/2022:09:00:17 +0800] [CGI] matches[1].rm_so=-1 D [21/May/2022:09:00:17 +0800] [CGI] Regular expression \".PrintSelfTestPage.\" D [21/May/2022:09:00:17 +0800] [CGI] matches[0].rm_so=0 D [21/May/2022:09:00:17 +0800] [CGI] matches[1].rm_so=-1 D [21/May/2022:09:00:17 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:17 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2804, file=22 D [21/May/2022:09:00:17 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:17 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:17 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:17 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2804, file=22 D [21/May/2022:09:00:17 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:17 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:17 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2804, file=22 D [21/May/2022:09:00:17 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:17 +0800] [Client 20] POST / HTTP/1.1 D [21/May/2022:09:00:17 +0800] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [21/May/2022:09:00:17 +0800] [Client 20] Read: status=200, state=6 D [21/May/2022:09:00:17 +0800] [Client 20] No authentication data provided. D [21/May/2022:09:00:17 +0800] [Client 20] 2.0 Get-Jobs 3 D [21/May/2022:09:00:17 +0800] Get-Jobs ipp://localhost:6310/printers/t20210525514lenovolj2400 D [21/May/2022:09:00:17 +0800] [Job 4] Loading attributes... D [21/May/2022:09:00:17 +0800] [Client 20] Returning IPP successful-ok for Get-Jobs (ipp://localhost:6310/printers/t20210525514lenovolj2400) from localhost. D [21/May/2022:09:00:17 +0800] [Client 20] Content-Length: 456 D [21/May/2022:09:00:17 +0800] [Client 20] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [21/May/2022:09:00:17 +0800] [Client 20] con->http=0x55fe0d0d6220 D [21/May/2022:09:00:17 +0800] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=456, response=0x55fe0d0d3cf0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [21/May/2022:09:00:17 +0800] [Client 20] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [21/May/2022:09:00:17 +0800] [Client 20] bytes=0, http_state=0, data_remaining=456 D [21/May/2022:09:00:17 +0800] [Client 20] Flushing write buffer. D [21/May/2022:09:00:17 +0800] [Client 20] New state is HTTP_STATE_WAITING D [21/May/2022:09:00:17 +0800] [Client 20] Waiting for request. D [21/May/2022:09:00:17 +0800] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [21/May/2022:09:00:17 +0800] [CGI] cgiClearVariables called. D [21/May/2022:09:00:17 +0800] [CGI] cgiSetVariable: SECTION=\"printers\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetVariable: TOTAL=\"1\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetVariable: SERVER_NAME=\"xxx.test.com\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: job_preserved[0]=\"1\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: job_printer_name[0]=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: job_printer_uri[0]=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: job_name[0]=\"Test Page\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: job_originating_user_name[0]=\"anonymous\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: time_at_completed[0]=\"novalue\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: time_at_creation[0]=\"2022年05月21日 星期六 09时00分05秒\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: time_at_processing[0]=\"2022年05月21日 星期六 09时00分05秒\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: job_id[0]=\"4\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: job_state[0]=\"6\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: job_impressions_completed[0]=\"1\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: job_k_octets[0]=\"1\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: job_hold_until[0]=\"no-hold\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetArray: job_printer_state_message[0]=\"Filter failed\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetVariable: PRINTER_NAME=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetVariable: THISURL=\"/printers/t20210525514lenovolj2400\" D [21/May/2022:09:00:17 +0800] [CGI] cgiSetVariable: SEARCH_DEST=\"t20210525514lenovolj2400\" D [21/May/2022:09:00:17 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:17 +0800] [Client 20] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [21/May/2022:09:00:17 +0800] [Client 20] Closing connection. D [21/May/2022:09:00:17 +0800] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [21/May/2022:09:00:17 +0800] PID 2804 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [21/May/2022:09:00:17 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:17 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2804, file=22 D [21/May/2022:09:00:17 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:17 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:17 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2804, file=22 D [21/May/2022:09:00:17 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:17 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:17 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:17 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2804, file=22 D [21/May/2022:09:00:17 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:17 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:17 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2804, file=22 D [21/May/2022:09:00:17 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:17 +0800] [Client 8] CGI data ready to be sent. D [21/May/2022:09:00:17 +0800] [Client 8] con->http=0x55fe0d095fe0 D [21/May/2022:09:00:17 +0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=2804, file=22 D [21/May/2022:09:00:17 +0800] [Client 8] Waiting for CGI data. D [21/May/2022:09:00:17 +0800] [Client 8] Sending 0-length chunk. D [21/May/2022:09:00:17 +0800] [Client 8] Flushing write buffer. D [21/May/2022:09:00:17 +0800] [Client 8] New state is HTTP_STATE_WAITING D [21/May/2022:09:00:17 +0800] [Client 8] Waiting for request. D [21/May/2022:09:00:17 +0800] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" I [21/May/2022:09:00:18 +0800] Expiring subscriptions... D [21/May/2022:09:00:58 +0800] [Client 11] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [21/May/2022:09:00:58 +0800] [Client 11] Closing connection. D [21/May/2022:09:00:58 +0800] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" I [21/May/2022:09:00:58 +0800] Expiring subscriptions... D [21/May/2022:09:00:58 +0800] [Job 2] Unloading... D [21/May/2022:09:00:58 +0800] [Job 3] Unloading... D [21/May/2022:09:00:58 +0800] Report: clients=1 D [21/May/2022:09:00:58 +0800] Report: jobs=3 D [21/May/2022:09:00:58 +0800] Report: jobs-active=1 D [21/May/2022:09:00:58 +0800] Report: printers=1 D [21/May/2022:09:00:58 +0800] Report: stringpool-string-count=1275 D [21/May/2022:09:00:58 +0800] Report: stringpool-alloc-bytes=11208 D [21/May/2022:09:00:58 +0800] Report: stringpool-total-bytes=24152 I [21/May/2022:09:00:59 +0800] Expiring subscriptions... D [21/May/2022:09:05:19 +0800] [Client 8] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [21/May/2022:09:05:19 +0800] [Client 8] Closing connection. D [21/May/2022:09:05:19 +0800] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" I [21/May/2022:09:05:19 +0800] Expiring subscriptions... D [21/May/2022:09:05:19 +0800] [Job 4] Unloading... D [21/May/2022:09:05:19 +0800] Report: clients=0 D [21/May/2022:09:05:19 +0800] Report: jobs=3 D [21/May/2022:09:05:19 +0800] Report: jobs-active=1 D [21/May/2022:09:05:19 +0800] Report: printers=1 D [21/May/2022:09:05:19 +0800] Report: stringpool-string-count=1232 D [21/May/2022:09:05:19 +0800] Report: stringpool-alloc-bytes=10248 D [21/May/2022:09:05:19 +0800] Report: stringpool-total-bytes=23160 I [21/May/2022:09:05:20 +0800] Expiring subscriptions...