CUPS :631/admin interface freezes

  • Done
  • quality assurance status badge
Details
3 participants
  • Brice Waegeneire
  • Marius Bakke
  • Tobias Geerinckx-Rice
Owner
unassigned
Submitted by
Tobias Geerinckx-Rice
Severity
normal
Merged with
T
T
Tobias Geerinckx-Rice wrote on 26 Jun 2020 23:18
(address . bug-guix@gnu.org)
877dvtsezh.fsf@nckx
Guix,

When visiting localhost:631/admin, the server appears to freeze
(blank page). However, exactly 2 minutes later content is served
and buttons like ‘Find New Printer’ [appear to] work without
delay.

Some error_log debug output below. Note the 2 neat 1-minute
time-outs.

It's likely that this is an upstream bug (but not quite this [1]).
I'll try upgrading CUPS and gathering more infos.

Kind regards,

T G-R


Toggle snippet (92 lines)
D [26/Jun/2020:22:30:44 +0200] [Client 1199] Waiting for CGI data.
D [26/Jun/2020:22:30:44 +0200] [CGI] admin.cgi started...
D [26/Jun/2020:22:30:44 +0200] [CGI] http=0x572bbd388140
D [26/Jun/2020:22:30:44 +0200] [CGI] cgiSetVariable:
SECTION=\"admin\"
D [26/Jun/2020:22:30:44 +0200] [CGI] cgiSetVariable:
REFRESH_PAGE=\"\"
D [26/Jun/2020:22:30:44 +0200] [CGI] org.cups.sid cookie not
found, initializing!
D [26/Jun/2020:22:30:44 +0200] [CGI] org.cups.sid cookie is
\"7be9c6067fac85f5930e650697b46a8e\"
D [26/Jun/2020:22:30:44 +0200] [CGI] No form data, showing main
menu...
D [26/Jun/2020:22:31:44 +0200] Report: clients=100
D [26/Jun/2020:22:31:44 +0200] Report: jobs=0
D [26/Jun/2020:22:31:44 +0200] Report: jobs-active=0
D [26/Jun/2020:22:31:44 +0200] Report: printers=1
D [26/Jun/2020:22:31:44 +0200] Report:
stringpool-string-count=2150
D [26/Jun/2020:22:31:44 +0200] Report:
stringpool-alloc-bytes=14480
D [26/Jun/2020:22:31:44 +0200] Report:
stringpool-total-bytes=43296
D [26/Jun/2020:22:32:44 +0200] [Client 807] Closing on unexpected
HTTP read state HTTP_STATE_GET_SEND.
D [26/Jun/2020:22:32:44 +0200] [Client 807] Closing connection.
D [26/Jun/2020:22:32:44 +0200] cupsdSetBusyState: newbusy="Active
clients", busy="Active clients"
I [26/Jun/2020:22:32:44 +0200] Resuming new connection
processing...
D [26/Jun/2020:22:32:44 +0200] Report: clients=99
D [26/Jun/2020:22:32:44 +0200] Report: jobs=0
D [26/Jun/2020:22:32:44 +0200] Report: jobs-active=0
D [26/Jun/2020:22:32:44 +0200] Report: printers=1
D [26/Jun/2020:22:32:44 +0200] Report:
stringpool-string-count=2150
D [26/Jun/2020:22:32:44 +0200] Report:
stringpool-alloc-bytes=14480
D [26/Jun/2020:22:32:44 +0200] Report:
stringpool-total-bytes=43296
D [26/Jun/2020:22:32:44 +0200] cupsdSetBusyState: newbusy="Active
clients", busy="Active clients"
D [26/Jun/2020:22:32:44 +0200] [Client 1200] Server address is
"/var/run/cups/cups.sock".
D [26/Jun/2020:22:32:44 +0200] [Client 1200] Accepted from
localhost (Domain)
D [26/Jun/2020:22:32:44 +0200] [Client 1200] Waiting for request.
W [26/Jun/2020:22:32:44 +0200] Max clients reached, holding new
connections...
D [26/Jun/2020:22:32:44 +0200] [Client 1200] GET
/admin/conf/cupsd.conf HTTP/1.1
D [26/Jun/2020:22:32:44 +0200] cupsdSetBusyState: newbusy="Active
clients", busy="Active clients"
D [26/Jun/2020:22:32:44 +0200] [Client 1200] Read: status=200,
state=3
D [26/Jun/2020:22:32:44 +0200] [Client 1200] No authentication
data provided.
D [26/Jun/2020:22:32:44 +0200] cupsdIsAuthorized: username=""
D [26/Jun/2020:22:32:44 +0200] [Client 1200] cupsdSendHeader:
code=401, type="text/html", auth_type=0
D [26/Jun/2020:22:32:44 +0200] [Client 1200] WWW-Authenticate:
Basic realm=\"CUPS\", PeerCred, Local trc=\"y\"
D [26/Jun/2020:22:32:44 +0200] [Client 1200] Closing connection.
D [26/Jun/2020:22:32:44 +0200] cupsdSetBusyState: newbusy="Active
clients", busy="Active clients"
I [26/Jun/2020:22:32:44 +0200] Resuming new connection
processing...
D [26/Jun/2020:22:32:44 +0200] [Client 811] Closing on unexpected
HTTP read state HTTP_STATE_GET_SEND.
D [26/Jun/2020:22:32:44 +0200] [Client 811] Closing connection.
D [26/Jun/2020:22:32:44 +0200] cupsdSetBusyState: newbusy="Active
clients", busy="Active clients"
D [26/Jun/2020:22:32:44 +0200] PID 7754
(/gnu/store/w6wnki4glaxxwmdh56fqf4yis3k7n8yg-cups-server-bin/lib/cups/cg>
D [26/Jun/2020:22:32:44 +0200] [Client 815] Closing on unexpected
HTTP read state HTTP_STATE_GET_SEND.
D [26/Jun/2020:22:32:44 +0200] [Client 815] Closing connection.
D [26/Jun/2020:22:32:44 +0200] cupsdSetBusyState: newbusy="Active
clients", busy="Active clients"
D [26/Jun/2020:22:32:44 +0200] PID 7755
(/gnu/store/w6wnki4glaxxwmdh56fqf4yis3k7n8yg-cups-server-bin/lib/cups/cg>
D [26/Jun/2020:22:32:44 +0200] [Client 819] Closing on unexpected
HTTP read state HTTP_STATE_GET_SEND.
D [26/Jun/2020:22:32:44 +0200] [Client 819] Closing connection.
D [26/Jun/2020:22:32:44 +0200] cupsdSetBusyState: newbusy="Active
clients", busy="Active clients"
D [26/Jun/2020:22:32:44 +0200] PID 7756
(/gnu/store/w6wnki4glaxxwmdh56fqf4yis3k7n8yg-cups-server-bin/lib/cups/cg>
D [26/Jun/2020:22:32:44 +0200] [Client 823] Closing on unexpected
HTTP read state HTTP_STATE_GET_SEND.
D [26/Jun/2020:22:32:44 +0200] [Client 823] Closing connection.
-----BEGIN PGP SIGNATURE-----

iHUEARYKAB0WIQT12iAyS4c9C3o4dnINsP+IT1VteQUCXvZmIgAKCRANsP+IT1Vt
eZh3AP9O1K3W7zPnC5ZNpazLouzkRLeFq2n5o+Ckng6hHOaKEgEA7Nz4kmDsMPUI
bo1ihvQuKVvrOjXREk6wyuiJ468QPQk=
=xDMe
-----END PGP SIGNATURE-----

M
M
Marius Bakke wrote on 27 Jun 2020 11:32
(address . control@debbugs.gnu.org)
871rm0dfb6.fsf@gnu.org
merge 39801 42068
thanks
B
B
Brice Waegeneire wrote on 19 Jun 2021 10:19
(address . control@debbugs.gnu.org)
E1luWCj-0004Gg-F1@debbugs.gnu.org
merge 49106 39801
quit
T
T
Tobias Geerinckx-Rice wrote on 24 Jun 2021 04:18
Re: CUPS :631/admin interface freezes
(address . 42068-done@debbugs.gnu.org)
87lf70xawq.fsf@nckx
I've pushed some patches I've been using since ‘forever’ to
core-updates. They simply remove the entire right-hand ‘Server’
form, and short-circuit the logic behind it that caused the long
delays.

An older work-around I tried was to simply link to the store
cupsd.conf in /etc/cups. This solves the immediate problem, and
even allows viewing (not editing) a few settings in said form.
It's an option for those who can't wait for core-updates to be
merged (don't forget to delete it next year… :-).

However, doing so merely hides the fact that this code is broken
with -c FILE by effectively circumventing the option in a very
sneaky way. A step back, not forward.

Kind regards,

T G-R
-----BEGIN PGP SIGNATURE-----

iIMEARYKACsWIQT12iAyS4c9C3o4dnINsP+IT1VteQUCYNPrhg0cbWVAdG9iaWFz
LmdyAAoJEA2w/4hPVW15H+IBAKjt+AhNQj4s0bLJFOP/wCHuYrUrH2F2AlZagSdO
rhohAQDx3cG4gjjxwQlonES1RG98PswbiFDOygxSGkJEkoFHCg==
=wVVw
-----END PGP SIGNATURE-----

Closed
?