GNU bug report logs - #42068
CUPS :631/admin interface freezes

Previous Next

Package: guix;

Reported by: Tobias Geerinckx-Rice <me <at> tobias.gr>

Date: Fri, 26 Jun 2020 21:19:02 UTC

Severity: normal

Merged with 39801, 49106

Done: Tobias Geerinckx-Rice <me <at> tobias.gr>

Bug is archived. No further changes may be made.

To add a comment to this bug, you must first unarchive it, by sending
a message to control AT debbugs.gnu.org, with unarchive 42068 in the body.
You can then email your comments to 42068 AT debbugs.gnu.org in the normal way.

Toggle the display of automated, internal messages from the tracker.

View this report as an mbox folder, status mbox, maintainer mbox


Report forwarded to bug-guix <at> gnu.org:
bug#42068; Package guix. (Fri, 26 Jun 2020 21:19:02 GMT) Full text and rfc822 format available.

Acknowledgement sent to Tobias Geerinckx-Rice <me <at> tobias.gr>:
New bug report received and forwarded. Copy sent to bug-guix <at> gnu.org. (Fri, 26 Jun 2020 21:19:02 GMT) Full text and rfc822 format available.

Message #5 received at submit <at> debbugs.gnu.org (full text, mbox):

From: Tobias Geerinckx-Rice <me <at> tobias.gr>
To: bug-guix <at> gnu.org
Subject: CUPS :631/admin interface freezes
Date: Fri, 26 Jun 2020 23:18:26 +0200
[Message part 1 (text/plain, inline)]
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

[1]: https://github.com/apple/cups/issues/5755

--8<---------------cut here---------------start------------->8---
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.
--8<---------------cut here---------------end--------------->8---
[signature.asc (application/pgp-signature, inline)]

Merged 39801 42068. Request was from Marius Bakke <marius <at> gnu.org> to control <at> debbugs.gnu.org. (Sat, 27 Jun 2020 09:34:01 GMT) Full text and rfc822 format available.

Merged 39801 42068 49106. Request was from Brice Waegeneire <brice <at> waegenei.re> to control <at> debbugs.gnu.org. (Sat, 19 Jun 2021 08:20:01 GMT) Full text and rfc822 format available.

Reply sent to Tobias Geerinckx-Rice <me <at> tobias.gr>:
You have taken responsibility. (Thu, 24 Jun 2021 02:19:02 GMT) Full text and rfc822 format available.

Notification sent to Tobias Geerinckx-Rice <me <at> tobias.gr>:
bug acknowledged by developer. (Thu, 24 Jun 2021 02:19:02 GMT) Full text and rfc822 format available.

Message #14 received at 42068-done <at> debbugs.gnu.org (full text, mbox):

From: Tobias Geerinckx-Rice <me <at> tobias.gr>
To: 42068-done <at> debbugs.gnu.org
Subject: Re: CUPS :631/admin interface freezes
Date: Thu, 24 Jun 2021 04:18:45 +0200
[Message part 1 (text/plain, inline)]
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
[signature.asc (application/pgp-signature, inline)]

Reply sent to Tobias Geerinckx-Rice <me <at> tobias.gr>:
You have taken responsibility. (Thu, 24 Jun 2021 02:19:02 GMT) Full text and rfc822 format available.

Notification sent to hot12shots <hot12shots <at> gmail.com>:
bug acknowledged by developer. (Thu, 24 Jun 2021 02:19:02 GMT) Full text and rfc822 format available.

Reply sent to Tobias Geerinckx-Rice <me <at> tobias.gr>:
You have taken responsibility. (Thu, 24 Jun 2021 02:19:03 GMT) Full text and rfc822 format available.

Notification sent to Christopher Howard <christopher <at> librehacker.com>:
bug acknowledged by developer. (Thu, 24 Jun 2021 02:19:03 GMT) Full text and rfc822 format available.

bug archived. Request was from Debbugs Internal Request <help-debbugs <at> gnu.org> to internal_control <at> debbugs.gnu.org. (Thu, 22 Jul 2021 11:24:04 GMT) Full text and rfc822 format available.

This bug report was last modified 2 years and 251 days ago.

Previous Next


GNU bug tracking system
Copyright (C) 1999 Darren O. Benham, 1997,2003 nCipher Corporation Ltd, 1994-97 Ian Jackson.