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
?
Your comment

This issue is archived.

To comment on this conversation send an email to 42068@debbugs.gnu.org

To respond to this issue using the mumi CLI, first switch to it
mumi current 42068
Then, you may apply the latest patchset in this issue (with sign off)
mumi am -- -s
Or, compose a reply to this issue
mumi compose
Or, send patches to this issue
mumi send-email *.patch