Re: Printing problem (was snapshot.debian.org)
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
Reply to: