[Date Prev][Date Next] [Thread Prev][Thread Next] [Date Index] [Thread Index]

Re: Printing problem (was snapshot.debian.org)



CUPS error log excerpt attached.
G


On Mon  6 Jun 2022, at 14:02, Gareth Evans <donotspam@fastmail.fm> wrote:
> On Mon  6 Jun 2022, at 13:05, rhkramer@gmail.com wrote:
>> On Monday, June 06, 2022 07:34:07 AM Gareth Evans wrote:
>>> On Mon  6 Jun 2022, at 12:19, Gareth Evans <donotspam@fastmail.fm> wrote:
>>> > I have a strange printing problem which can be replicated on two
>>> > identical printers on two different networks, when printing to wireless
>>> > driverless IPP with Brother MFC-L2740DW printers from Bullseye, whether
>>> > the printer is auto-detected or manually added via ocalhost:631 or
>>> > system-config-printer.
>>
>> Is there a facility to run a test print directly from the printer?  If so, 
>> does that work?
>
> Hello,
>
> Yes, the printer prints test pages and reports from its own console, 
> and documents via wifi from my iphone, but not from Debian.  Even 11.0 
> with non-updated cups.  Same issue with an identical printer on another 
> network - that does print from Buster.  I half suspected a router 
> problem, but same issues when Bullseye laptop and my printer are linked 
> via iphone wifi hotspot.  Same issue when my Bullseye laptop is linked 
> via EE mobile broadband router (locally) to an identical printer (in 
> another place, 10 miles away, so can't check for differences regularly).
>
> After using system-config-printer to print a test page on an 
> auto-detected driverless IPP profile, connected via 2.5GHz wifi to a 
> router, to which the Bullseye laptop is connected via 5GHz wifi, I get 
> no output, and:
>
> $ lpstat -t
> scheduler is running
> no system default destination
> device for Brother_MFC_L2740DW_series: 
> implicitclass://Brother_MFC_L2740DW_series/
> Brother_MFC_L2740DW_series accepting requests since Mon 06 Jun 2022 
> 13:12:54 BST
> printer Brother_MFC_L2740DW_series is idle.  enabled since Mon 06 Jun 
> 2022 13:12:54 BST
> 	No suitable destination host found by cups-browsed.
> Brother_MFC_L2740DW_series-6 user              1024   Mon 06 Jun 2022 
> 13:12:48 BST
>
> After changing laptop to 2.5GHz wifi...
>
> $ lpstat -t
> scheduler is running
> no system default destination
> device for Brother_MFC_L2740DW_series: 
> implicitclass://Brother_MFC_L2740DW_series/
> Brother_MFC_L2740DW_series accepting requests since Mon 06 Jun 2022 
> 13:14:40 BST
> printer Brother_MFC_L2740DW_series is idle.  enabled since Mon 06 Jun 
> 2022 13:14:40 BST
> 	Printer disappeared or cups-browsed shutdown
> Brother_MFC_L2740DW_series-6 user              1024   Mon 06 Jun 2022 
> 13:12:48 BST
>
> $ sudo systemctl status cups-browsed
> [sudo] password for user: 
> ● cups-browsed.service - Make remote CUPS printers available locally
>      Loaded: loaded (/lib/systemd/system/cups-browsed.service; enabled; vendor >
>      Active: active (running) since Mon 2022-06-06 13:04:25 BST; 11min ago
>    Main PID: 1666 (cups-browsed)
>       Tasks: 3 (limit: 14146)
>      Memory: 3.8M
>         CPU: 209ms
>      CGroup: /system.slice/cups-browsed.service
>              └─1666 /usr/sbin/cups-browsed
>
> Jun 06 13:04:25 qwerty systemd[1]: Started Make remote CUPS printers available >
>
> *I deleted 5GHz wifi profile from Network Manager Edit Connections*
> $ sudo reboot
>
> $ sudo systemctl status cups-browsed
> [sudo] password for user: 
> ● cups-browsed.service - Make remote CUPS printers available locally
>      Loaded: loaded (/lib/systemd/system/cups-browsed.service; enabled; vendor >
>      Active: active (running) since Mon 2022-06-06 13:27:27 BST; 3min 13s ago
>    Main PID: 2059 (cups-browsed)
>       Tasks: 3 (limit: 14146)
>      Memory: 2.9M
>         CPU: 100ms
>      CGroup: /system.slice/cups-browsed.service
>              └─2059 /usr/sbin/cups-browsed
>
> Jun 06 13:27:27 qwerty systemd[1]: Started Make remote CUPS printers available >
> lines 1-11/11 (END)
>
>
> $ lpstat -t
> scheduler is running
> no system default destination
> device for Brother_MFC_L2740DW_series: 
> implicitclass://Brother_MFC_L2740DW_series/
> Brother_MFC_L2740DW_series accepting requests since Mon 06 Jun 2022 
> 13:27:46 BST
> printer Brother_MFC_L2740DW_series is idle.  enabled since Mon 06 Jun 
> 2022 13:27:46 BST
> 	Printer disappeared or cups-browsed shutdown
> Brother_MFC_L2740DW_series-6 user              1024   Mon 06 Jun 2022 
> 13:12:48 BST
>
> *Turned printer off and on again*
>
> $ lpstat -t
> scheduler is running
> no system default destination
> device for Brother_MFC_L2740DW_series: 
> implicitclass://Brother_MFC_L2740DW_series/
> Brother_MFC_L2740DW_series accepting requests since Mon 06 Jun 2022 
> 13:31:39 BST
> printer Brother_MFC_L2740DW_series now printing 
> Brother_MFC_L2740DW_series-6.  enabled since Mon 06 Jun 2022 13:31:39 
> BST
> 	Waiting for job to complete.
> Brother_MFC_L2740DW_series-6 user              1024   Mon 06 Jun 2022 
> 13:12:48 BST
>
>
> $ lpstat -t
> scheduler is running
> no system default destination
> device for Brother_MFC_L2740DW_series: 
> implicitclass://Brother_MFC_L2740DW_series/
> Brother_MFC_L2740DW_series accepting requests since Mon 06 Jun 2022 
> 13:32:06 BST
> printer Brother_MFC_L2740DW_series is idle.  enabled since Mon 06 Jun 
> 2022 13:32:06 BST
>
> But nothing printed.
>
> /var/log/cups/error_log attached for most recent test page.
> (/etc/cups/cupsd.conf loglevel = debug)
>
> --------------
> A few potential oddities:
>
> [line no] log text
> [143-150] D [06/Jun/2022:13:32:05 +0100] [Client 48] Waiting for 
> request.
> D [06/Jun/2022:13:32:05 +0100] [Client 48] HTTP_STATE_WAITING Closing 
> for error 32 (Broken pipe)
> D [06/Jun/2022:13:32:05 +0100] [Client 48] Closing connection.
> D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Printing 
> jobs and dirty files", busy="Active clients, printing jobs, and dirty 
> files"
> D [06/Jun/2022:13:32:05 +0100] [Client 47] POST / HTTP/1.1
> D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Active 
> clients, printing jobs, and dirty files", busy="Printing jobs and dirty 
> files"
> D [06/Jun/2022:13:32:05 +0100] [Client 47] Read: status=200, state=6
> D [06/Jun/2022:13:32:05 +0100] [Client 47] No authentication data 
> provided.
>
> [153] D [06/Jun/2022:13:32:05 +0100] [Client 47] Returning IPP 
> successful-ok for CUPS-Get-Classes (no URI) from localhost.
>
> [219-250] D [06/Jun/2022:13:32:05 +0100] [Client 47] 2.0 Get-Jobs 1
> D [06/Jun/2022:13:32:05 +0100] Get-Jobs 
> ipp://localhost/printers/Brother_MFC_L2740DW_series
> D [06/Jun/2022:13:32:05 +0100] [Client 47] Returning IPP successful-ok 
> for Get-Jobs (ipp://localhost/printers/Brother_MFC_L2740DW_series) from 
> localhost.
> D [06/Jun/2022:13:32:05 +0100] [Client 47] Content-Length: 109
> D [06/Jun/2022:13:32:05 +0100] [Client 47] cupsdSendHeader: code=200, 
> type="application/ipp", auth_type=0
> D [06/Jun/2022:13:32:05 +0100] [Client 47] con->http=0x56135fe8e630
> D [06/Jun/2022:13:32:05 +0100] [Client 47] cupsdWriteClient error=0, 
> used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
> data_remaining=109, response=0x56135fe91e80(IPP_STATE_DATA), 
> pipe_pid=0, file=-1
> D [06/Jun/2022:13:32:05 +0100] [Client 47] Writing IPP response, 
> ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
> D [06/Jun/2022:13:32:05 +0100] [Client 47] bytes=0, http_state=0, 
> data_remaining=109
> D [06/Jun/2022:13:32:05 +0100] [Client 47] Flushing write buffer.
> D [06/Jun/2022:13:32:05 +0100] [Client 47] New state is 
> HTTP_STATE_WAITING
> D [06/Jun/2022:13:32:05 +0100] [Client 47] Waiting for request.
> D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Printing 
> jobs and dirty files", busy="Active clients, printing jobs, and dirty 
> files"
> D [06/Jun/2022:13:32:05 +0100] [Client 47] POST / HTTP/1.1
> D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Active 
> clients, printing jobs, and dirty files", busy="Printing jobs and dirty 
> files"
> D [06/Jun/2022:13:32:05 +0100] [Client 47] Read: status=200, state=6
> D [06/Jun/2022:13:32:05 +0100] [Client 47] No authentication data 
> provided.
> D [06/Jun/2022:13:32:05 +0100] [Client 47] 2.0 Get-Jobs 8
> D [06/Jun/2022:13:32:05 +0100] Get-Jobs ipp://localhost/
> D [06/Jun/2022:13:32:05 +0100] [Client 47] Returning IPP successful-ok 
> for Get-Jobs (ipp://localhost/) from localhost.
> D [06/Jun/2022:13:32:05 +0100] [Client 47] Content-Length: 378
> D [06/Jun/2022:13:32:05 +0100] [Client 47] cupsdSendHeader: code=200, 
> type="application/ipp", auth_type=0
> D [06/Jun/2022:13:32:05 +0100] [Client 47] con->http=0x56135fe8e630
> D [06/Jun/2022:13:32:05 +0100] [Client 47] cupsdWriteClient error=0, 
> used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, 
> data_remaining=378, response=0x56135fe92750(IPP_STATE_DATA), 
> pipe_pid=0, file=-1
> D [06/Jun/2022:13:32:05 +0100] [Client 47] Writing IPP response, 
> ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
> D [06/Jun/2022:13:32:05 +0100] [Client 47] bytes=0, http_state=0, 
> data_remaining=378
> D [06/Jun/2022:13:32:05 +0100] [Client 47] Flushing write buffer.
> D [06/Jun/2022:13:32:05 +0100] [Client 47] New state is 
> HTTP_STATE_WAITING
> D [06/Jun/2022:13:32:05 +0100] [Client 47] Waiting for request.
> D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Printing 
> jobs and dirty files", busy="Active clients, printing jobs, and dirty 
> files"
> D [06/Jun/2022:13:32:05 +0100] [Client 47] HTTP_STATE_WAITING Closing 
> for error 32 (Broken pipe)
> D [06/Jun/2022:13:32:05 +0100] [Client 47] Closing connection.
> ---------
>
> Is that number of client IDs (retries?) normal?
>
> FWIW, do these permissions look right?
>
> $ ls -l /etc/cups
> total 94
> -rw------- 1 root lp     111 Jun  5 18:18 classes.conf
> -rw-r--r-- 1 root root 30436 Mar 14 21:03 cups-browsed.conf
> -rw-r--r-- 1 root root  6457 Jun  6 12:41 cupsd.conf
> -rw-r--r-- 1 root root  3047 May 23 21:03 cups-files.conf
> drwxr-xr-x 2 root root     2 May 23 21:03 interfaces
> drwxr-xr-x 2 root lp       4 Jun  6 13:27 ppd
> -rw------- 1 root lp     914 Jun  6 13:32 printers.conf
> -rw------- 1 root lp     732 Jun  6 13:27 printers.conf.O
> -rw-r--r-- 1 root root   240 Jun  6 12:35 raw.convs
> -rw-r--r-- 1 root root   211 Jun  6 12:35 raw.types
> -rw-r--r-- 1 root root   142 May 27  2021 snmp.conf
> drwx------ 2 root lp       2 May 23 21:03 ssl
> -rw-r----- 1 root lp    3315 Jun  6 13:32 subscriptions.conf
> -rw-r----- 1 root lp    3312 Jun  6 13:27 subscriptions.conf.O
>
>
> $ apt policy cups
> cups:
>   Installed: 2.3.3op2-3+deb11u2
>   Candidate: 2.3.3op2-3+deb11u2
>   Version table:
>  *** 2.3.3op2-3+deb11u2 500
>         500 http://deb.debian.org/debian-security 
> bullseye-security/main amd64 Packages
>         100 /var/lib/dpkg/status
>      2.3.3op2-3+deb11u1 500
>         500 http://deb.debian.org/debian bullseye/main amd64 Packages
>
>
> $ sudo apt list |grep cups|grep installed
> cups-browsed/stable,now 1.28.7-1+deb11u1 amd64 [installed]
> cups-bsd/stable-security,now 2.3.3op2-3+deb11u2 amd64 
> [installed,automatic]
> cups-client/stable-security,now 2.3.3op2-3+deb11u2 amd64 [installed]
> cups-common/stable-security,stable-security,now 2.3.3op2-3+deb11u2 all 
> [installed]
> cups-core-drivers/stable-security,now 2.3.3op2-3+deb11u2 amd64 
> [installed]
> cups-daemon/stable-security,now 2.3.3op2-3+deb11u2 amd64 [installed]
> cups-filters-core-drivers/stable,now 1.28.7-1+deb11u1 amd64 [installed]
> cups-filters/stable,now 1.28.7-1+deb11u1 amd64 [installed]
> cups-ipp-utils/stable-security,now 2.3.3op2-3+deb11u2 amd64 [installed]
> cups-pk-helper/stable,now 0.2.6-1+b1 amd64 [installed]
> cups-ppdc/stable-security,now 2.3.3op2-3+deb11u2 amd64 [installed]
> cups-server-common/stable-security,stable-security,now 
> 2.3.3op2-3+deb11u2 all [installed]
> cups/stable-security,now 2.3.3op2-3+deb11u2 amd64 [installed]
> libcups2/stable-security,now 2.3.3op2-3+deb11u2 amd64 [installed]
> libcupsfilters1/stable,now 1.28.7-1+deb11u1 amd64 [installed]
> python3-cups/stable,now 2.0.1-4+b1 amd64 [installed,automatic]
> python3-cupshelpers/stable,stable,now 1.5.14-1 all [installed,automatic]
>
> I also sometimes get "File '' not found" in system-config-printer's 
> "printer state" field when printing test pages from there, although 
> other issues are the same printing from LibreOffice or kate.
>
> Many thanks!
> Gareth
D [06/Jun/2022:13:32:04 +0100] [Job 6] IPP/2.0 Get-Printer-Attributes #21
D [06/Jun/2022:13:32:04 +0100] [Job 6] ---- operation-attributes-tag ----
D [06/Jun/2022:13:32:04 +0100] [Job 6] attributes-charset charset utf-8
D [06/Jun/2022:13:32:04 +0100] [Job 6] attributes-natural-language naturalLanguage en-gb
D [06/Jun/2022:13:32:04 +0100] [Job 6] printer-uri uri ipp://mfcl2740dw.local:631/ipp/faxout
D [06/Jun/2022:13:32:04 +0100] [Job 6] requesting-user-name nameWithoutLanguage user
D [06/Jun/2022:13:32:04 +0100] [Job 6] requested-attributes 1setOf keyword compression-supported,copies-supported,cups-version,document-format-supported,job-password-encryption-supported,marker-colors,marker-high-levels,marker-levels,marker-low-levels,marker-message,marker-names,marker-types,media-col-supported,multiple-document-handling-supported,operations-supported,print-color-mode-supported,printer-alert,printer-alert-description,printer-is-accepting-jobs,printer-mandatory-job-attributes,printer-state,printer-state-message,printer-state-reasons
D [06/Jun/2022:13:32:04 +0100] [Job 6] ---- end-of-attributes-tag ----
I [06/Jun/2022:13:32:04 +0100] Expiring subscriptions...
D [06/Jun/2022:13:32:04 +0100] [Job 6] update_reasons(attr=1(none), s=\"(null)\")
D [06/Jun/2022:13:32:04 +0100] [Job 6] Get-Printer-Attributes: successful-ok-ignored-or-substituted-attributes (successful-ok-ignored-or-substituted-attributes)
D [06/Jun/2022:13:32:04 +0100] [Job 6] ---- operation-attributes-tag ----
D [06/Jun/2022:13:32:04 +0100] [Job 6] attributes-charset charset utf-8
D [06/Jun/2022:13:32:04 +0100] [Job 6] attributes-natural-language naturalLanguage en-gb
D [06/Jun/2022:13:32:04 +0100] [Job 6] ---- unsupported-attributes-tag ----
D [06/Jun/2022:13:32:04 +0100] [Job 6] requested-attributes 1setOf keyword cups-version,job-password-encryption-supported,marker-message,multiple-document-handling-supported,printer-alert,printer-alert-description,printer-mandatory-job-attributes,printer-state-message
D [06/Jun/2022:13:32:04 +0100] [Job 6] ---- printer-attributes-tag ----
D [06/Jun/2022:13:32:04 +0100] [Job 6] compression-supported keyword none
D [06/Jun/2022:13:32:04 +0100] [Job 6] copies-supported rangeOfInteger 1-1
D [06/Jun/2022:13:32:04 +0100] [Job 6] document-format-supported 1setOf mimeMediaType image/urf,application/octet-stream
D [06/Jun/2022:13:32:04 +0100] [Job 6] marker-colors nameWithLanguage #000000[en]
D [06/Jun/2022:13:32:04 +0100] [Job 6] marker-high-levels integer 100
D [06/Jun/2022:13:32:04 +0100] [Job 6] marker-levels integer 70
D [06/Jun/2022:13:32:04 +0100] [Job 6] marker-low-levels integer 10
D [06/Jun/2022:13:32:04 +0100] [Job 6] marker-names nameWithLanguage BK[en]
D [06/Jun/2022:13:32:04 +0100] [Job 6] marker-types keyword toner
D [06/Jun/2022:13:32:04 +0100] [Job 6] media-col-supported 1setOf keyword media-type,media-size,media-top-margin,media-left-margin,media-right-margin,media-bottom-margin,media-source
D [06/Jun/2022:13:32:04 +0100] [Job 6] operations-supported 1setOf enum Print-Job,Validate-Job,Create-Job,Send-Document,Cancel-Job,Get-Job-Attributes,Get-Jobs,Get-Printer-Attributes,Identify-Printer
D [06/Jun/2022:13:32:04 +0100] [Job 6] print-color-mode-supported 1setOf keyword monochrome,auto
D [06/Jun/2022:13:32:04 +0100] [Job 6] printer-is-accepting-jobs boolean true
D [06/Jun/2022:13:32:04 +0100] [Job 6] printer-state enum idle
D [06/Jun/2022:13:32:04 +0100] [Job 6] printer-state-reasons keyword none
D [06/Jun/2022:13:32:04 +0100] [Job 6] ---- end-of-attributes-tag ----
D [06/Jun/2022:13:32:04 +0100] [Job 6] IPP/2.0 Get-Job-Attributes #22
D [06/Jun/2022:13:32:04 +0100] [Job 6] ---- operation-attributes-tag ----
D [06/Jun/2022:13:32:04 +0100] [Job 6] attributes-charset charset utf-8
D [06/Jun/2022:13:32:04 +0100] [Job 6] attributes-natural-language naturalLanguage en-gb
D [06/Jun/2022:13:32:04 +0100] [Job 6] printer-uri uri ipp://mfcl2740dw.local:631/ipp/faxout
D [06/Jun/2022:13:32:04 +0100] [Job 6] job-id integer 82
D [06/Jun/2022:13:32:04 +0100] [Job 6] requesting-user-name nameWithoutLanguage user
D [06/Jun/2022:13:32:04 +0100] [Job 6] requested-attributes 1setOf keyword job-id,job-impressions-completed,job-media-sheets-completed,job-name,job-originating-user-name,job-state,job-state-reasons
D [06/Jun/2022:13:32:04 +0100] [Job 6] ---- end-of-attributes-tag ----
D [06/Jun/2022:13:32:04 +0100] [Job 6] update_reasons(attr=1(none), s=\"(null)\")
D [06/Jun/2022:13:32:04 +0100] [Job 6] Get-Printer-Attributes: successful-ok-ignored-or-substituted-attributes (successful-ok-ignored-or-substituted-attributes)
D [06/Jun/2022:13:32:04 +0100] [Job 6] ---- operation-attributes-tag ----
D [06/Jun/2022:13:32:04 +0100] [Job 6] attributes-charset charset utf-8
D [06/Jun/2022:13:32:04 +0100] [Job 6] attributes-natural-language naturalLanguage en-gb
D [06/Jun/2022:13:32:04 +0100] [Job 6] ---- unsupported-attributes-tag ----
D [06/Jun/2022:13:32:04 +0100] [Job 6] requested-attributes 1setOf keyword cups-version,job-password-encryption-supported,marker-message,multiple-document-handling-supported,printer-alert,printer-alert-description,printer-mandatory-job-attributes,printer-state-message
D [06/Jun/2022:13:32:04 +0100] [Job 6] ---- printer-attributes-tag ----
D [06/Jun/2022:13:32:04 +0100] [Job 6] compression-supported keyword none
D [06/Jun/2022:13:32:04 +0100] [Job 6] copies-supported rangeOfInteger 1-1
D [06/Jun/2022:13:32:04 +0100] [Job 6] document-format-supported 1setOf mimeMediaType image/urf,application/octet-stream
D [06/Jun/2022:13:32:04 +0100] [Job 6] marker-colors nameWithLanguage #000000[en]
D [06/Jun/2022:13:32:04 +0100] [Job 6] marker-high-levels integer 100
D [06/Jun/2022:13:32:04 +0100] [Job 6] marker-levels integer 70
D [06/Jun/2022:13:32:04 +0100] [Job 6] marker-low-levels integer 10
D [06/Jun/2022:13:32:04 +0100] [Job 6] marker-names nameWithLanguage BK[en]
D [06/Jun/2022:13:32:04 +0100] [Job 6] marker-types keyword toner
D [06/Jun/2022:13:32:04 +0100] [Job 6] media-col-supported 1setOf keyword media-type,media-size,media-top-margin,media-left-margin,media-right-margin,media-bottom-margin,media-source
D [06/Jun/2022:13:32:04 +0100] [Job 6] operations-supported 1setOf enum Print-Job,Validate-Job,Create-Job,Send-Document,Cancel-Job,Get-Job-Attributes,Get-Jobs,Get-Printer-Attributes,Identify-Printer
D [06/Jun/2022:13:32:04 +0100] [Job 6] print-color-mode-supported 1setOf keyword monochrome,auto
D [06/Jun/2022:13:32:04 +0100] [Job 6] printer-is-accepting-jobs boolean true
D [06/Jun/2022:13:32:04 +0100] [Job 6] printer-state enum idle
D [06/Jun/2022:13:32:04 +0100] [Job 6] printer-state-reasons keyword none
D [06/Jun/2022:13:32:04 +0100] [Job 6] ---- end-of-attributes-tag ----
D [06/Jun/2022:13:32:04 +0100] [Job 6] Get-Job-Attributes: successful-ok-ignored-or-substituted-attributes (successful-ok-ignored-or-substituted-attributes)
D [06/Jun/2022:13:32:04 +0100] [Job 6] ---- operation-attributes-tag ----
D [06/Jun/2022:13:32:04 +0100] [Job 6] attributes-charset charset utf-8
D [06/Jun/2022:13:32:04 +0100] [Job 6] attributes-natural-language naturalLanguage en-gb
D [06/Jun/2022:13:32:04 +0100] [Job 6] ---- unsupported-attributes-tag ----
D [06/Jun/2022:13:32:04 +0100] [Job 6] requested-attributes keyword job-media-sheets-completed
D [06/Jun/2022:13:32:04 +0100] [Job 6] ---- job-attributes-tag ----
D [06/Jun/2022:13:32:04 +0100] [Job 6] job-id integer 82
D [06/Jun/2022:13:32:04 +0100] [Job 6] job-impressions-completed integer 0
D [06/Jun/2022:13:32:04 +0100] [Job 6] job-name nameWithoutLanguage Test Page
D [06/Jun/2022:13:32:04 +0100] [Job 6] job-originating-user-name nameWithoutLanguage user
D [06/Jun/2022:13:32:04 +0100] [Job 6] job-state enum canceled
D [06/Jun/2022:13:32:04 +0100] [Job 6] job-state-reasons keyword job-canceled-at-device
D [06/Jun/2022:13:32:04 +0100] [Job 6] ---- end-of-attributes-tag ----
D [06/Jun/2022:13:32:04 +0100] [Job 6] PAGE: total 0
D [06/Jun/2022:13:32:04 +0100] cupsdMarkDirty(----S)
D [06/Jun/2022:13:32:04 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:04 +0100] cupsdMarkDirty(---J-)
D [06/Jun/2022:13:32:04 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:04 +0100] [Job 6] IPP/2.0 Get-Printer-Attributes #24
D [06/Jun/2022:13:32:04 +0100] [Job 6] ---- operation-attributes-tag ----
D [06/Jun/2022:13:32:04 +0100] [Job 6] attributes-charset charset utf-8
D [06/Jun/2022:13:32:04 +0100] [Job 6] attributes-natural-language naturalLanguage en-gb
D [06/Jun/2022:13:32:04 +0100] [Job 6] printer-uri uri ipp://mfcl2740dw.local:631/ipp/faxout
D [06/Jun/2022:13:32:04 +0100] [Job 6] requesting-user-name nameWithoutLanguage user
D [06/Jun/2022:13:32:04 +0100] [Job 6] requested-attributes 1setOf keyword compression-supported,copies-supported,cups-version,document-format-supported,job-password-encryption-supported,marker-colors,marker-high-levels,marker-levels,marker-low-levels,marker-message,marker-names,marker-types,media-col-supported,multiple-document-handling-supported,operations-supported,print-color-mode-supported,printer-alert,printer-alert-description,printer-is-accepting-jobs,printer-mandatory-job-attributes,printer-state,printer-state-message,printer-state-reasons
D [06/Jun/2022:13:32:04 +0100] [Job 6] ---- end-of-attributes-tag ----
D [06/Jun/2022:13:32:04 +0100] [Notifier] state=3
D [06/Jun/2022:13:32:04 +0100] [Notifier] JobProgress
D [06/Jun/2022:13:32:05 +0100] [Job 6] (monitor) Get-Job-Attributes: successful-ok-ignored-or-substituted-attributes (successful-ok-ignored-or-substituted-attributes)
D [06/Jun/2022:13:32:05 +0100] [Job 6] (monitor) job-state = canceled
D [06/Jun/2022:13:32:05 +0100] [Job 6] (monitor) job_canceled = -1
I [06/Jun/2022:13:32:05 +0100] Expiring subscriptions...
D [06/Jun/2022:13:32:05 +0100] [Job 6] (monitor) job-state = canceled
D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:05 +0100] [Client 47] Server address is "/run/cups/cups.sock".
D [06/Jun/2022:13:32:05 +0100] [Client 47] Accepted from localhost (Domain)
D [06/Jun/2022:13:32:05 +0100] [Client 47] Waiting for request.
D [06/Jun/2022:13:32:05 +0100] [Client 47] POST / HTTP/1.1
D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [06/Jun/2022:13:32:05 +0100] [Client 47] Read: status=200, state=6
D [06/Jun/2022:13:32:05 +0100] [Client 47] No authentication data provided.
D [06/Jun/2022:13:32:05 +0100] [Client 47] 2.0 CUPS-Get-Default 1
D [06/Jun/2022:13:32:05 +0100] CUPS-Get-Default
D [06/Jun/2022:13:32:05 +0100] CUPS-Get-Default client-error-not-found: No default printer.
D [06/Jun/2022:13:32:05 +0100] [Client 47] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost.
D [06/Jun/2022:13:32:05 +0100] [Client 47] Content-Length: 113
D [06/Jun/2022:13:32:05 +0100] [Client 47] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Jun/2022:13:32:05 +0100] [Client 47] con->http=0x56135fe8e630
D [06/Jun/2022:13:32:05 +0100] [Client 47] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x56135fe91dd0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Jun/2022:13:32:05 +0100] [Client 47] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Jun/2022:13:32:05 +0100] [Client 47] bytes=0, http_state=0, data_remaining=113
D [06/Jun/2022:13:32:05 +0100] [Client 47] Flushing write buffer.
D [06/Jun/2022:13:32:05 +0100] [Client 47] New state is HTTP_STATE_WAITING
D [06/Jun/2022:13:32:05 +0100] [Client 47] Waiting for request.
D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [06/Jun/2022:13:32:05 +0100] [Client 47] POST / HTTP/1.1
D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:05 +0100] [Client 47] Read: status=200, state=6
D [06/Jun/2022:13:32:05 +0100] [Client 47] No authentication data provided.
D [06/Jun/2022:13:32:05 +0100] [Client 47] 2.0 CUPS-Get-Printers 2
D [06/Jun/2022:13:32:05 +0100] CUPS-Get-Printers
D [06/Jun/2022:13:32:05 +0100] [Client 47] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [06/Jun/2022:13:32:05 +0100] [Client 47] Content-Length: 1541
D [06/Jun/2022:13:32:05 +0100] [Client 47] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Jun/2022:13:32:05 +0100] [Client 47] con->http=0x56135fe8e630
D [06/Jun/2022:13:32:05 +0100] [Client 47] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1541, response=0x56135fe920a0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Jun/2022:13:32:05 +0100] [Client 47] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Jun/2022:13:32:05 +0100] [Client 47] bytes=0, http_state=0, data_remaining=1541
D [06/Jun/2022:13:32:05 +0100] [Client 47] Flushing write buffer.
D [06/Jun/2022:13:32:05 +0100] [Client 47] New state is HTTP_STATE_WAITING
D [06/Jun/2022:13:32:05 +0100] [Client 47] Waiting for request.
D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:05 +0100] [Client 48] Server address is "/run/cups/cups.sock".
D [06/Jun/2022:13:32:05 +0100] [Client 48] Accepted from localhost (Domain)
D [06/Jun/2022:13:32:05 +0100] [Client 48] Waiting for request.
D [06/Jun/2022:13:32:05 +0100] [Client 48] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [06/Jun/2022:13:32:05 +0100] [Client 48] Closing connection.
D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [06/Jun/2022:13:32:05 +0100] [Client 47] POST / HTTP/1.1
D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:05 +0100] [Client 47] Read: status=200, state=6
D [06/Jun/2022:13:32:05 +0100] [Client 47] No authentication data provided.
D [06/Jun/2022:13:32:05 +0100] [Client 47] 2.0 CUPS-Get-Classes 3
D [06/Jun/2022:13:32:05 +0100] CUPS-Get-Classes
D [06/Jun/2022:13:32:05 +0100] [Client 47] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost.
D [06/Jun/2022:13:32:05 +0100] [Client 47] Content-Length: 75
D [06/Jun/2022:13:32:05 +0100] [Client 47] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Jun/2022:13:32:05 +0100] [Client 47] con->http=0x56135fe8e630
D [06/Jun/2022:13:32:05 +0100] [Client 47] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x56135fe91f60(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Jun/2022:13:32:05 +0100] [Client 47] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Jun/2022:13:32:05 +0100] [Client 47] bytes=0, http_state=0, data_remaining=75
D [06/Jun/2022:13:32:05 +0100] [Client 47] Flushing write buffer.
D [06/Jun/2022:13:32:05 +0100] [Client 47] New state is HTTP_STATE_WAITING
D [06/Jun/2022:13:32:05 +0100] [Client 47] Waiting for request.
D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [06/Jun/2022:13:32:05 +0100] [Client 47] POST / HTTP/1.1
D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:05 +0100] [Client 47] Read: status=200, state=6
D [06/Jun/2022:13:32:05 +0100] [Client 47] No authentication data provided.
D [06/Jun/2022:13:32:05 +0100] [Client 47] 2.0 CUPS-Get-Printers 4
D [06/Jun/2022:13:32:05 +0100] CUPS-Get-Printers
D [06/Jun/2022:13:32:05 +0100] [Client 47] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [06/Jun/2022:13:32:05 +0100] [Client 47] Content-Length: 254
D [06/Jun/2022:13:32:05 +0100] [Client 47] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Jun/2022:13:32:05 +0100] [Client 47] con->http=0x56135fe8e630
D [06/Jun/2022:13:32:05 +0100] [Client 47] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=254, response=0x56135fe91d50(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Jun/2022:13:32:05 +0100] [Client 47] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Jun/2022:13:32:05 +0100] [Client 47] bytes=0, http_state=0, data_remaining=254
D [06/Jun/2022:13:32:05 +0100] [Client 47] Flushing write buffer.
D [06/Jun/2022:13:32:05 +0100] [Client 47] New state is HTTP_STATE_WAITING
D [06/Jun/2022:13:32:05 +0100] [Client 47] Waiting for request.
D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [06/Jun/2022:13:32:05 +0100] [Client 47] POST / HTTP/1.1
D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:05 +0100] [Client 47] Read: status=200, state=6
D [06/Jun/2022:13:32:05 +0100] [Client 47] No authentication data provided.
D [06/Jun/2022:13:32:05 +0100] [Client 47] 2.0 CUPS-Get-Printers 5
D [06/Jun/2022:13:32:05 +0100] CUPS-Get-Printers
D [06/Jun/2022:13:32:05 +0100] [Client 47] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [06/Jun/2022:13:32:05 +0100] [Client 47] Content-Length: 238
D [06/Jun/2022:13:32:05 +0100] [Client 47] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Jun/2022:13:32:05 +0100] [Client 47] con->http=0x56135fe8e630
D [06/Jun/2022:13:32:05 +0100] [Client 47] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=238, response=0x56135fe925a0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Jun/2022:13:32:05 +0100] [Client 47] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Jun/2022:13:32:05 +0100] [Client 47] bytes=0, http_state=0, data_remaining=238
D [06/Jun/2022:13:32:05 +0100] [Client 47] Flushing write buffer.
D [06/Jun/2022:13:32:05 +0100] [Client 47] New state is HTTP_STATE_WAITING
D [06/Jun/2022:13:32:05 +0100] [Client 47] Waiting for request.
D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [06/Jun/2022:13:32:05 +0100] [Client 47] POST / HTTP/1.1
D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:05 +0100] [Client 47] Read: status=200, state=6
D [06/Jun/2022:13:32:05 +0100] [Client 47] No authentication data provided.
D [06/Jun/2022:13:32:05 +0100] [Client 47] 2.0 CUPS-Get-Printers 6
D [06/Jun/2022:13:32:05 +0100] CUPS-Get-Printers
D [06/Jun/2022:13:32:05 +0100] [Client 47] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [06/Jun/2022:13:32:05 +0100] [Client 47] Content-Length: 538
D [06/Jun/2022:13:32:05 +0100] [Client 47] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Jun/2022:13:32:05 +0100] [Client 47] con->http=0x56135fe8e630
D [06/Jun/2022:13:32:05 +0100] [Client 47] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=538, response=0x56135fe91d50(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Jun/2022:13:32:05 +0100] [Client 47] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Jun/2022:13:32:05 +0100] [Client 47] bytes=0, http_state=0, data_remaining=538
D [06/Jun/2022:13:32:05 +0100] [Client 47] Flushing write buffer.
D [06/Jun/2022:13:32:05 +0100] [Client 47] New state is HTTP_STATE_WAITING
D [06/Jun/2022:13:32:05 +0100] [Client 47] Waiting for request.
D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [06/Jun/2022:13:32:05 +0100] [Client 47] POST / HTTP/1.1
D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:05 +0100] [Client 47] Read: status=200, state=6
D [06/Jun/2022:13:32:05 +0100] [Client 47] No authentication data provided.
D [06/Jun/2022:13:32:05 +0100] [Client 47] 2.0 Get-Jobs 1
D [06/Jun/2022:13:32:05 +0100] Get-Jobs ipp://localhost/printers/Brother_MFC_L2740DW_series
D [06/Jun/2022:13:32:05 +0100] [Client 47] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/Brother_MFC_L2740DW_series) from localhost.
D [06/Jun/2022:13:32:05 +0100] [Client 47] Content-Length: 109
D [06/Jun/2022:13:32:05 +0100] [Client 47] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Jun/2022:13:32:05 +0100] [Client 47] con->http=0x56135fe8e630
D [06/Jun/2022:13:32:05 +0100] [Client 47] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=109, response=0x56135fe91e80(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Jun/2022:13:32:05 +0100] [Client 47] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Jun/2022:13:32:05 +0100] [Client 47] bytes=0, http_state=0, data_remaining=109
D [06/Jun/2022:13:32:05 +0100] [Client 47] Flushing write buffer.
D [06/Jun/2022:13:32:05 +0100] [Client 47] New state is HTTP_STATE_WAITING
D [06/Jun/2022:13:32:05 +0100] [Client 47] Waiting for request.
D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [06/Jun/2022:13:32:05 +0100] [Client 47] POST / HTTP/1.1
D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:05 +0100] [Client 47] Read: status=200, state=6
D [06/Jun/2022:13:32:05 +0100] [Client 47] No authentication data provided.
D [06/Jun/2022:13:32:05 +0100] [Client 47] 2.0 Get-Jobs 8
D [06/Jun/2022:13:32:05 +0100] Get-Jobs ipp://localhost/
D [06/Jun/2022:13:32:05 +0100] [Client 47] Returning IPP successful-ok for Get-Jobs (ipp://localhost/) from localhost.
D [06/Jun/2022:13:32:05 +0100] [Client 47] Content-Length: 378
D [06/Jun/2022:13:32:05 +0100] [Client 47] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Jun/2022:13:32:05 +0100] [Client 47] con->http=0x56135fe8e630
D [06/Jun/2022:13:32:05 +0100] [Client 47] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=378, response=0x56135fe92750(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Jun/2022:13:32:05 +0100] [Client 47] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Jun/2022:13:32:05 +0100] [Client 47] bytes=0, http_state=0, data_remaining=378
D [06/Jun/2022:13:32:05 +0100] [Client 47] Flushing write buffer.
D [06/Jun/2022:13:32:05 +0100] [Client 47] New state is HTTP_STATE_WAITING
D [06/Jun/2022:13:32:05 +0100] [Client 47] Waiting for request.
D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [06/Jun/2022:13:32:05 +0100] [Client 47] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [06/Jun/2022:13:32:05 +0100] [Client 47] Closing connection.
D [06/Jun/2022:13:32:05 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:06 +0100] [Job 6] update_reasons(attr=1(none), s=\"(null)\")
D [06/Jun/2022:13:32:06 +0100] [Job 6] Get-Printer-Attributes: successful-ok-ignored-or-substituted-attributes (successful-ok-ignored-or-substituted-attributes)
D [06/Jun/2022:13:32:06 +0100] [Job 6] ---- operation-attributes-tag ----
D [06/Jun/2022:13:32:06 +0100] [Job 6] attributes-charset charset utf-8
D [06/Jun/2022:13:32:06 +0100] [Job 6] attributes-natural-language naturalLanguage en-gb
D [06/Jun/2022:13:32:06 +0100] [Job 6] ---- unsupported-attributes-tag ----
D [06/Jun/2022:13:32:06 +0100] [Job 6] requested-attributes 1setOf keyword cups-version,job-password-encryption-supported,marker-message,multiple-document-handling-supported,printer-alert,printer-alert-description,printer-mandatory-job-attributes,printer-state-message
D [06/Jun/2022:13:32:06 +0100] [Job 6] ---- printer-attributes-tag ----
D [06/Jun/2022:13:32:06 +0100] [Job 6] compression-supported keyword none
D [06/Jun/2022:13:32:06 +0100] [Job 6] copies-supported rangeOfInteger 1-1
I [06/Jun/2022:13:32:06 +0100] Expiring subscriptions...
D [06/Jun/2022:13:32:06 +0100] [Job 6] document-format-supported 1setOf mimeMediaType image/urf,application/octet-stream
D [06/Jun/2022:13:32:06 +0100] [Job 6] marker-colors nameWithLanguage #000000[en]
D [06/Jun/2022:13:32:06 +0100] [Job 6] marker-high-levels integer 100
D [06/Jun/2022:13:32:06 +0100] [Job 6] marker-levels integer 70
D [06/Jun/2022:13:32:06 +0100] [Job 6] marker-low-levels integer 10
D [06/Jun/2022:13:32:06 +0100] [Job 6] marker-names nameWithLanguage BK[en]
D [06/Jun/2022:13:32:06 +0100] [Job 6] marker-types keyword toner
D [06/Jun/2022:13:32:06 +0100] [Job 6] media-col-supported 1setOf keyword media-type,media-size,media-top-margin,media-left-margin,media-right-margin,media-bottom-margin,media-source
D [06/Jun/2022:13:32:06 +0100] [Job 6] operations-supported 1setOf enum Print-Job,Validate-Job,Create-Job,Send-Document,Cancel-Job,Get-Job-Attributes,Get-Jobs,Get-Printer-Attributes,Identify-Printer
D [06/Jun/2022:13:32:06 +0100] [Job 6] print-color-mode-supported 1setOf keyword monochrome,auto
D [06/Jun/2022:13:32:06 +0100] [Job 6] printer-is-accepting-jobs boolean true
D [06/Jun/2022:13:32:06 +0100] [Job 6] printer-state enum idle
D [06/Jun/2022:13:32:06 +0100] [Job 6] printer-state-reasons keyword none
D [06/Jun/2022:13:32:06 +0100] [Job 6] ---- end-of-attributes-tag ----
D [06/Jun/2022:13:32:06 +0100] [Job 6] ATTR: auth-info-required=none
D [06/Jun/2022:13:32:06 +0100] load_ppd: Loading /var/cache/cups/Brother_MFC_L2740DW_series.data...
D [06/Jun/2022:13:32:06 +0100] cupsdRegisterPrinter(p=0x56135fd3fbc0(Brother_MFC_L2740DW_series))
D [06/Jun/2022:13:32:06 +0100] cupsdMarkDirty(P----)
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:06 +0100] [Job 6] The IPP Backend exited with the status 0
D [06/Jun/2022:13:32:06 +0100] [Client 23] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [06/Jun/2022:13:32:06 +0100] [Client 23] Closing connection.
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:06 +0100] [Job 6] PID 7347 (/usr/lib/cups/backend/implicitclass) exited with no errors.
D [06/Jun/2022:13:32:06 +0100] cupsdMarkDirty(P----)
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:06 +0100] [Job 6] time-at-completed=1654518726
D [06/Jun/2022:13:32:06 +0100] cupsdMarkDirty(----S)
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
I [06/Jun/2022:13:32:06 +0100] [Job 6] Job completed.
I [06/Jun/2022:13:32:06 +0100] Expiring subscriptions...
D [06/Jun/2022:13:32:06 +0100] cupsdMarkDirty(---J-)
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:06 +0100] cupsdMarkDirty(----S)
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:06 +0100] cupsdMarkDirty(---J-)
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:06 +0100] cupsdMarkDirty(---J-)
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:06 +0100] [Notifier] state=3
D [06/Jun/2022:13:32:06 +0100] [Notifier] JobCompleted
D [06/Jun/2022:13:32:06 +0100] [Notifier] state=3
D [06/Jun/2022:13:32:06 +0100] [Notifier] PrinterStateChanged
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"
D [06/Jun/2022:13:32:06 +0100] [Client 49] Server address is "/run/cups/cups.sock".
D [06/Jun/2022:13:32:06 +0100] [Client 49] Accepted from localhost (Domain)
D [06/Jun/2022:13:32:06 +0100] [Client 49] Waiting for request.
D [06/Jun/2022:13:32:06 +0100] [Client 49] POST / HTTP/1.1
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [06/Jun/2022:13:32:06 +0100] [Client 49] Read: status=200, state=6
D [06/Jun/2022:13:32:06 +0100] [Client 49] No authentication data provided.
D [06/Jun/2022:13:32:06 +0100] [Client 49] 2.0 Get-Notifications 35
D [06/Jun/2022:13:32:06 +0100] Get-Notifications /
D [06/Jun/2022:13:32:06 +0100] cupsdIsAuthorized: requesting-user-name="user"
D [06/Jun/2022:13:32:06 +0100] [Client 49] Returning IPP successful-ok for Get-Notifications (/) from localhost.
D [06/Jun/2022:13:32:06 +0100] [Client 49] Content-Length: 2861
D [06/Jun/2022:13:32:06 +0100] [Client 49] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Jun/2022:13:32:06 +0100] [Client 49] con->http=0x56135fe30c40
D [06/Jun/2022:13:32:06 +0100] [Client 49] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2861, response=0x56135fe80b20(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Jun/2022:13:32:06 +0100] [Client 49] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Jun/2022:13:32:06 +0100] [Client 49] bytes=0, http_state=0, data_remaining=2861
D [06/Jun/2022:13:32:06 +0100] [Client 49] Flushing write buffer.
D [06/Jun/2022:13:32:06 +0100] [Client 49] New state is HTTP_STATE_WAITING
D [06/Jun/2022:13:32:06 +0100] [Client 49] Waiting for request.
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [06/Jun/2022:13:32:06 +0100] [Client 50] Server address is "/run/cups/cups.sock".
D [06/Jun/2022:13:32:06 +0100] [Client 50] Accepted from localhost (Domain)
D [06/Jun/2022:13:32:06 +0100] [Client 50] Waiting for request.
D [06/Jun/2022:13:32:06 +0100] [Client 50] POST / HTTP/1.1
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [06/Jun/2022:13:32:06 +0100] [Client 50] Read: status=200, state=6
D [06/Jun/2022:13:32:06 +0100] [Client 50] No authentication data provided.
D [06/Jun/2022:13:32:06 +0100] [Client 50] 2.0 Get-Job-Attributes 36
D [06/Jun/2022:13:32:06 +0100] Get-Job-Attributes ipp://localhost/jobs/6
D [06/Jun/2022:13:32:06 +0100] [Client 50] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/6) from localhost.
D [06/Jun/2022:13:32:06 +0100] [Client 50] Content-Length: 95
D [06/Jun/2022:13:32:06 +0100] [Client 50] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Jun/2022:13:32:06 +0100] [Client 50] con->http=0x56135fe93b20
D [06/Jun/2022:13:32:06 +0100] [Client 50] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=95, response=0x56135fe19800(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Jun/2022:13:32:06 +0100] [Client 50] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Jun/2022:13:32:06 +0100] [Client 50] bytes=0, http_state=0, data_remaining=95
D [06/Jun/2022:13:32:06 +0100] [Client 50] Flushing write buffer.
D [06/Jun/2022:13:32:06 +0100] [Client 50] New state is HTTP_STATE_WAITING
D [06/Jun/2022:13:32:06 +0100] [Client 50] Waiting for request.
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [06/Jun/2022:13:32:06 +0100] [Client 50] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [06/Jun/2022:13:32:06 +0100] [Client 50] Closing connection.
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [06/Jun/2022:13:32:06 +0100] [Client 51] Server address is "/run/cups/cups.sock".
D [06/Jun/2022:13:32:06 +0100] [Client 51] Accepted from localhost (Domain)
D [06/Jun/2022:13:32:06 +0100] [Client 51] Waiting for request.
D [06/Jun/2022:13:32:06 +0100] [Client 51] POST / HTTP/1.1
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [06/Jun/2022:13:32:06 +0100] [Client 51] Read: status=200, state=6
D [06/Jun/2022:13:32:06 +0100] [Client 51] No authentication data provided.
D [06/Jun/2022:13:32:06 +0100] [Client 51] 2.0 Get-Job-Attributes 37
D [06/Jun/2022:13:32:06 +0100] Get-Job-Attributes ipp://localhost/jobs/6
D [06/Jun/2022:13:32:06 +0100] [Client 51] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/6) from localhost.
D [06/Jun/2022:13:32:06 +0100] [Client 51] Content-Length: 95
D [06/Jun/2022:13:32:06 +0100] [Client 51] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Jun/2022:13:32:06 +0100] [Client 51] con->http=0x56135fe93b20
D [06/Jun/2022:13:32:06 +0100] [Client 51] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=95, response=0x56135fe19800(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Jun/2022:13:32:06 +0100] [Client 51] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Jun/2022:13:32:06 +0100] [Client 51] bytes=0, http_state=0, data_remaining=95
D [06/Jun/2022:13:32:06 +0100] [Client 51] Flushing write buffer.
D [06/Jun/2022:13:32:06 +0100] [Client 51] New state is HTTP_STATE_WAITING
D [06/Jun/2022:13:32:06 +0100] [Client 51] Waiting for request.
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [06/Jun/2022:13:32:06 +0100] [Client 51] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [06/Jun/2022:13:32:06 +0100] [Client 51] Closing connection.
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [06/Jun/2022:13:32:06 +0100] [Client 52] Server address is "/run/cups/cups.sock".
D [06/Jun/2022:13:32:06 +0100] [Client 52] Accepted from localhost (Domain)
D [06/Jun/2022:13:32:06 +0100] [Client 52] Waiting for request.
D [06/Jun/2022:13:32:06 +0100] [Client 52] POST / HTTP/1.1
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [06/Jun/2022:13:32:06 +0100] [Client 52] Read: status=200, state=6
D [06/Jun/2022:13:32:06 +0100] [Client 52] No authentication data provided.
D [06/Jun/2022:13:32:06 +0100] [Client 52] 2.0 Get-Job-Attributes 38
D [06/Jun/2022:13:32:06 +0100] Get-Job-Attributes ipp://localhost/jobs/6
D [06/Jun/2022:13:32:06 +0100] [Client 52] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/6) from localhost.
D [06/Jun/2022:13:32:06 +0100] [Client 52] Content-Length: 95
D [06/Jun/2022:13:32:06 +0100] [Client 52] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Jun/2022:13:32:06 +0100] [Client 52] con->http=0x56135fe93b20
D [06/Jun/2022:13:32:06 +0100] [Client 52] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=95, response=0x56135fe19800(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Jun/2022:13:32:06 +0100] [Client 52] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Jun/2022:13:32:06 +0100] [Client 52] bytes=0, http_state=0, data_remaining=95
D [06/Jun/2022:13:32:06 +0100] [Client 52] Flushing write buffer.
D [06/Jun/2022:13:32:06 +0100] [Client 52] New state is HTTP_STATE_WAITING
D [06/Jun/2022:13:32:06 +0100] [Client 52] Waiting for request.
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [06/Jun/2022:13:32:06 +0100] [Client 52] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [06/Jun/2022:13:32:06 +0100] [Client 52] Closing connection.
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [06/Jun/2022:13:32:06 +0100] [Client 53] Server address is "/run/cups/cups.sock".
D [06/Jun/2022:13:32:06 +0100] [Client 53] Accepted from localhost (Domain)
D [06/Jun/2022:13:32:06 +0100] [Client 53] Waiting for request.
D [06/Jun/2022:13:32:06 +0100] [Client 53] POST / HTTP/1.1
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [06/Jun/2022:13:32:06 +0100] [Client 53] Read: status=200, state=6
D [06/Jun/2022:13:32:06 +0100] [Client 53] No authentication data provided.
D [06/Jun/2022:13:32:06 +0100] [Client 53] 2.0 Get-Printer-Attributes 39
D [06/Jun/2022:13:32:06 +0100] Get-Printer-Attributes ipp://qwerty/printers/Brother_MFC_L2740DW_series
D [06/Jun/2022:13:32:06 +0100] [Client 53] Returning IPP successful-ok for Get-Printer-Attributes (ipp://qwerty/printers/Brother_MFC_L2740DW_series) from localhost.
D [06/Jun/2022:13:32:06 +0100] [Client 53] Content-Length: 134
D [06/Jun/2022:13:32:06 +0100] [Client 53] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Jun/2022:13:32:06 +0100] [Client 53] con->http=0x56135fe93b20
D [06/Jun/2022:13:32:06 +0100] [Client 53] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=134, response=0x56135fe7bf60(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Jun/2022:13:32:06 +0100] [Client 53] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Jun/2022:13:32:06 +0100] [Client 53] bytes=0, http_state=0, data_remaining=134
D [06/Jun/2022:13:32:06 +0100] [Client 53] Flushing write buffer.
D [06/Jun/2022:13:32:06 +0100] [Client 53] New state is HTTP_STATE_WAITING
D [06/Jun/2022:13:32:06 +0100] [Client 53] Waiting for request.
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [06/Jun/2022:13:32:06 +0100] [Client 49] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [06/Jun/2022:13:32:06 +0100] [Client 49] Closing connection.
D [06/Jun/2022:13:32:06 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [06/Jun/2022:13:32:07 +0100] Expiring subscriptions...
I [06/Jun/2022:13:32:10 +0100] Saving printers.conf...
I [06/Jun/2022:13:32:10 +0100] Generating printcap /run/cups/printcap...
I [06/Jun/2022:13:32:10 +0100] Saving job.cache...
I [06/Jun/2022:13:32:10 +0100] Saving subscriptions.conf...
D [06/Jun/2022:13:32:10 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
I [06/Jun/2022:13:32:10 +0100] Expiring subscriptions...
D [06/Jun/2022:13:32:53 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [06/Jun/2022:13:32:53 +0100] [Client 54] Server address is "/run/cups/cups.sock".
D [06/Jun/2022:13:32:53 +0100] [Client 54] Accepted from localhost (Domain)
D [06/Jun/2022:13:32:53 +0100] [Client 54] Waiting for request.
I [06/Jun/2022:13:32:53 +0100] Expiring subscriptions...
D [06/Jun/2022:13:32:53 +0100] cupsdAddCert: Adding certificate for PID 0
D [06/Jun/2022:13:32:53 +0100] Report: clients=7
D [06/Jun/2022:13:32:53 +0100] Report: jobs=1
D [06/Jun/2022:13:32:53 +0100] Report: jobs-active=0
D [06/Jun/2022:13:32:53 +0100] Report: printers=1
D [06/Jun/2022:13:32:53 +0100] Report: stringpool-string-count=10118
D [06/Jun/2022:13:32:53 +0100] Report: stringpool-alloc-bytes=11752
D [06/Jun/2022:13:32:53 +0100] Report: stringpool-total-bytes=228832
D [06/Jun/2022:13:32:53 +0100] [Client 54] POST / HTTP/1.1
D [06/Jun/2022:13:32:53 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [06/Jun/2022:13:32:53 +0100] [Client 54] Read: status=200, state=6
D [06/Jun/2022:13:32:53 +0100] [Client 54] No authentication data provided.
D [06/Jun/2022:13:32:53 +0100] [Client 54] 2.0 CUPS-Get-Default 1
D [06/Jun/2022:13:32:53 +0100] CUPS-Get-Default
D [06/Jun/2022:13:32:53 +0100] CUPS-Get-Default client-error-not-found: No default printer.
D [06/Jun/2022:13:32:53 +0100] [Client 54] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost.
D [06/Jun/2022:13:32:53 +0100] [Client 54] Content-Length: 113
D [06/Jun/2022:13:32:53 +0100] [Client 54] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Jun/2022:13:32:53 +0100] [Client 54] con->http=0x56135fe30c40
D [06/Jun/2022:13:32:53 +0100] [Client 54] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x56135fe19800(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Jun/2022:13:32:53 +0100] [Client 54] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Jun/2022:13:32:53 +0100] [Client 54] bytes=0, http_state=0, data_remaining=113
D [06/Jun/2022:13:32:53 +0100] [Client 54] Flushing write buffer.
D [06/Jun/2022:13:32:53 +0100] [Client 54] New state is HTTP_STATE_WAITING
D [06/Jun/2022:13:32:53 +0100] [Client 54] Waiting for request.
D [06/Jun/2022:13:32:53 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [06/Jun/2022:13:32:53 +0100] [Client 54] POST / HTTP/1.1
D [06/Jun/2022:13:32:53 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [06/Jun/2022:13:32:53 +0100] [Client 54] Read: status=200, state=6
D [06/Jun/2022:13:32:53 +0100] [Client 54] No authentication data provided.
D [06/Jun/2022:13:32:53 +0100] [Client 54] 2.0 CUPS-Get-Printers 2
D [06/Jun/2022:13:32:53 +0100] CUPS-Get-Printers
D [06/Jun/2022:13:32:53 +0100] [Client 54] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [06/Jun/2022:13:32:53 +0100] [Client 54] Content-Length: 1515
D [06/Jun/2022:13:32:53 +0100] [Client 54] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Jun/2022:13:32:53 +0100] [Client 54] con->http=0x56135fe30c40
D [06/Jun/2022:13:32:53 +0100] [Client 54] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1515, response=0x56135fe7bfe0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Jun/2022:13:32:53 +0100] [Client 54] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Jun/2022:13:32:53 +0100] [Client 54] bytes=0, http_state=0, data_remaining=1515
D [06/Jun/2022:13:32:53 +0100] [Client 54] Flushing write buffer.
D [06/Jun/2022:13:32:53 +0100] [Client 54] New state is HTTP_STATE_WAITING
D [06/Jun/2022:13:32:53 +0100] [Client 54] Waiting for request.
D [06/Jun/2022:13:32:53 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [06/Jun/2022:13:32:54 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [06/Jun/2022:13:32:54 +0100] [Client 55] Server address is "/run/cups/cups.sock".
D [06/Jun/2022:13:32:54 +0100] [Client 55] Accepted from localhost (Domain)
D [06/Jun/2022:13:32:54 +0100] [Client 55] Waiting for request.
I [06/Jun/2022:13:32:54 +0100] Expiring subscriptions...
D [06/Jun/2022:13:32:54 +0100] [Client 55] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [06/Jun/2022:13:32:54 +0100] [Client 55] Closing connection.
D [06/Jun/2022:13:32:54 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [06/Jun/2022:13:32:54 +0100] [Client 54] POST / HTTP/1.1
D [06/Jun/2022:13:32:54 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [06/Jun/2022:13:32:54 +0100] [Client 54] Read: status=200, state=6
D [06/Jun/2022:13:32:54 +0100] [Client 54] No authentication data provided.
D [06/Jun/2022:13:32:54 +0100] [Client 54] 2.0 CUPS-Get-Classes 3
D [06/Jun/2022:13:32:54 +0100] CUPS-Get-Classes
D [06/Jun/2022:13:32:54 +0100] [Client 54] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost.
D [06/Jun/2022:13:32:54 +0100] [Client 54] Content-Length: 75
D [06/Jun/2022:13:32:54 +0100] [Client 54] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Jun/2022:13:32:54 +0100] [Client 54] con->http=0x56135fe30c40
D [06/Jun/2022:13:32:54 +0100] [Client 54] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x56135fe80b20(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Jun/2022:13:32:54 +0100] [Client 54] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Jun/2022:13:32:54 +0100] [Client 54] bytes=0, http_state=0, data_remaining=75
D [06/Jun/2022:13:32:54 +0100] [Client 54] Flushing write buffer.
D [06/Jun/2022:13:32:54 +0100] [Client 54] New state is HTTP_STATE_WAITING
D [06/Jun/2022:13:32:54 +0100] [Client 54] Waiting for request.
D [06/Jun/2022:13:32:54 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [06/Jun/2022:13:32:54 +0100] [Client 54] POST / HTTP/1.1
D [06/Jun/2022:13:32:54 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [06/Jun/2022:13:32:54 +0100] [Client 54] Read: status=200, state=6
D [06/Jun/2022:13:32:54 +0100] [Client 54] No authentication data provided.
D [06/Jun/2022:13:32:54 +0100] [Client 54] 2.0 CUPS-Get-Printers 4
D [06/Jun/2022:13:32:54 +0100] CUPS-Get-Printers
D [06/Jun/2022:13:32:54 +0100] [Client 54] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [06/Jun/2022:13:32:54 +0100] [Client 54] Content-Length: 254
D [06/Jun/2022:13:32:54 +0100] [Client 54] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Jun/2022:13:32:54 +0100] [Client 54] con->http=0x56135fe30c40
D [06/Jun/2022:13:32:54 +0100] [Client 54] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=254, response=0x56135fe1af10(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Jun/2022:13:32:54 +0100] [Client 54] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Jun/2022:13:32:54 +0100] [Client 54] bytes=0, http_state=0, data_remaining=254
D [06/Jun/2022:13:32:54 +0100] [Client 54] Flushing write buffer.
D [06/Jun/2022:13:32:54 +0100] [Client 54] New state is HTTP_STATE_WAITING
D [06/Jun/2022:13:32:54 +0100] [Client 54] Waiting for request.
D [06/Jun/2022:13:32:54 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [06/Jun/2022:13:32:54 +0100] [Client 54] POST / HTTP/1.1
D [06/Jun/2022:13:32:54 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [06/Jun/2022:13:32:54 +0100] [Client 54] Read: status=200, state=6
D [06/Jun/2022:13:32:54 +0100] [Client 54] No authentication data provided.
D [06/Jun/2022:13:32:54 +0100] [Client 54] 2.0 CUPS-Get-Printers 5
D [06/Jun/2022:13:32:54 +0100] CUPS-Get-Printers
D [06/Jun/2022:13:32:54 +0100] [Client 54] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [06/Jun/2022:13:32:54 +0100] [Client 54] Content-Length: 210
D [06/Jun/2022:13:32:54 +0100] [Client 54] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Jun/2022:13:32:54 +0100] [Client 54] con->http=0x56135fe30c40
D [06/Jun/2022:13:32:54 +0100] [Client 54] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=210, response=0x56135fe192e0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Jun/2022:13:32:54 +0100] [Client 54] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Jun/2022:13:32:54 +0100] [Client 54] bytes=0, http_state=0, data_remaining=210
D [06/Jun/2022:13:32:54 +0100] [Client 54] Flushing write buffer.
D [06/Jun/2022:13:32:54 +0100] [Client 54] New state is HTTP_STATE_WAITING
D [06/Jun/2022:13:32:54 +0100] [Client 54] Waiting for request.
D [06/Jun/2022:13:32:54 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [06/Jun/2022:13:32:54 +0100] [Client 54] POST / HTTP/1.1
D [06/Jun/2022:13:32:54 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [06/Jun/2022:13:32:54 +0100] [Client 54] Read: status=200, state=6
D [06/Jun/2022:13:32:54 +0100] [Client 54] No authentication data provided.
D [06/Jun/2022:13:32:54 +0100] [Client 54] 2.0 CUPS-Get-Printers 6
D [06/Jun/2022:13:32:54 +0100] CUPS-Get-Printers
D [06/Jun/2022:13:32:54 +0100] [Client 54] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [06/Jun/2022:13:32:54 +0100] [Client 54] Content-Length: 484
D [06/Jun/2022:13:32:54 +0100] [Client 54] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Jun/2022:13:32:54 +0100] [Client 54] con->http=0x56135fe30c40
D [06/Jun/2022:13:32:54 +0100] [Client 54] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=484, response=0x56135fe1af10(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Jun/2022:13:32:54 +0100] [Client 54] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Jun/2022:13:32:54 +0100] [Client 54] bytes=0, http_state=0, data_remaining=484
D [06/Jun/2022:13:32:54 +0100] [Client 54] Flushing write buffer.
D [06/Jun/2022:13:32:54 +0100] [Client 54] New state is HTTP_STATE_WAITING
D [06/Jun/2022:13:32:54 +0100] [Client 54] Waiting for request.
D [06/Jun/2022:13:32:54 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [06/Jun/2022:13:32:54 +0100] [Client 54] POST / HTTP/1.1
D [06/Jun/2022:13:32:54 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [06/Jun/2022:13:32:54 +0100] [Client 54] Read: status=200, state=6
D [06/Jun/2022:13:32:54 +0100] [Client 54] No authentication data provided.
D [06/Jun/2022:13:32:54 +0100] [Client 54] 2.0 Get-Jobs 7
D [06/Jun/2022:13:32:54 +0100] Get-Jobs ipp://localhost/
D [06/Jun/2022:13:32:54 +0100] [Client 54] Returning IPP successful-ok for Get-Jobs (ipp://localhost/) from localhost.
D [06/Jun/2022:13:32:54 +0100] [Client 54] Content-Length: 75
D [06/Jun/2022:13:32:54 +0100] [Client 54] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Jun/2022:13:32:54 +0100] [Client 54] con->http=0x56135fe30c40
D [06/Jun/2022:13:32:54 +0100] [Client 54] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x56135fe8aa70(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Jun/2022:13:32:54 +0100] [Client 54] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Jun/2022:13:32:54 +0100] [Client 54] bytes=0, http_state=0, data_remaining=75
D [06/Jun/2022:13:32:54 +0100] [Client 54] Flushing write buffer.
D [06/Jun/2022:13:32:54 +0100] [Client 54] New state is HTTP_STATE_WAITING
D [06/Jun/2022:13:32:54 +0100] [Client 54] Waiting for request.
D [06/Jun/2022:13:32:54 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [06/Jun/2022:13:32:54 +0100] [Client 54] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [06/Jun/2022:13:32:54 +0100] [Client 54] Closing connection.
D [06/Jun/2022:13:32:54 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [06/Jun/2022:13:32:55 +0100] Expiring subscriptions...

Reply to: