CUPS error log excerpt attached.
G
On Mon 6 Jun 2022, at 14:02, Gareth Evans <donots...@fastmail.fm> wrote:
> On Mon 6 Jun 2022, at 13:05, rhkra...@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 <donots...@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...
