Hi, Kenshi,
Kenshi Muto schrieb:
>>When starting cupsys with "Browsing Off", it eats all available CPU
>>time, using about 20% in user space and 80% in kernel space.
>
> Hmm, I tried it on my two environments, but I couldn't find any problem.
My co-developer seems to have the same problem on his machine (also
using debian testing, and a similar, but not necessarily identical
configuration).
>>When I attach strace to the /usr/sbin/cupsd process with strace -p $PID,
>>it spits out the the following lines repeatedly:
>>
>>select(1024, [0 2 3], [], NULL, {1, 0}) = 1 (in [2], left {1, 0})
>>time(NULL) = 1106817837
>>time(NULL) = 1106817837
>>select(1024, [0 2 3], [], NULL, {1, 0}) = 1 (in [2], left {1, 0})
>>time(NULL) = 1106817837
>>time(NULL) = 1106817837
>
> Hmm, it's hard to find where is this...
> Can you run gdb?
I'll try, and submit the results as soon as I have them.
>>I also attached my cupsd.conf to this report. If you need more
>>information, please ask.
>
> Yes, I want /etc/cups/printers.conf and /var/log/cups/error_log with
> LogLevel debug.
/etc/cups/printers.conf does not define any printers, currently.
I attached a /var/log/cups/error_log that with LogLevel debug that shows
startup and shutdown, and some idle time of using 100% cpu between.
Thanks,
Markus
--
markus schaber | dipl. informatiker
logi-track ag | rennweg 14-16 | ch 8001 zürich
phone +41-43-888 62 52 | fax +41-43-888 62 53
mailto:[EMAIL PROTECTED] | www.logi-track.com
# Printer configuration file for CUPS v1.1.21rc1
# Written by cupsd on Thu Apr 1 10:32:22 2004
I [27/Jan/2005:10:15:16 +0100] Listening to 7f000001:631
I [27/Jan/2005:10:15:16 +0100] Polling a170202:631
I [27/Jan/2005:10:15:16 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [27/Jan/2005:10:15:16 +0100] Configured for up to 100 clients.
I [27/Jan/2005:10:15:16 +0100] Allowing up to 100 client connections per host.
I [27/Jan/2005:10:15:16 +0100] Full reload is required.
I [27/Jan/2005:10:15:18 +0100] LoadPPDs: Read "/etc/cups/ppds.dat", 2348 PPDs...
I [27/Jan/2005:10:15:18 +0100] LoadPPDs: No new or changed PPDs...
I [27/Jan/2005:10:15:18 +0100] Full reload complete.
I [27/Jan/2005:10:16:01 +0100] Scheduler shutting down normally.
I [27/Jan/2005:10:17:06 +0100] Listening to 7f000001:631
I [27/Jan/2005:10:17:06 +0100] Polling a170202:631
I [27/Jan/2005:10:17:06 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [27/Jan/2005:10:17:06 +0100] Configured for up to 100 clients.
I [27/Jan/2005:10:17:06 +0100] Allowing up to 100 client connections per host.
I [27/Jan/2005:10:17:06 +0100] Full reload is required.
I [27/Jan/2005:10:17:06 +0100] LoadPPDs: Read "/etc/cups/ppds.dat", 2348 PPDs...
I [27/Jan/2005:10:17:06 +0100] LoadPPDs: No new or changed PPDs...
I [27/Jan/2005:10:17:06 +0100] Full reload complete.
I [27/Jan/2005:10:17:06 +0100] Added remote printer "hp-laserjet-2300d"...
I [27/Jan/2005:10:17:29 +0100] Scheduler shutting down normally.
I [27/Jan/2005:10:17:30 +0100] Listening to 7f000001:631
I [27/Jan/2005:10:17:30 +0100] Polling a170202:631
I [27/Jan/2005:10:17:30 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [27/Jan/2005:10:17:30 +0100] Configured for up to 100 clients.
I [27/Jan/2005:10:17:30 +0100] Allowing up to 100 client connections per host.
I [27/Jan/2005:10:17:30 +0100] Full reload is required.
I [27/Jan/2005:10:17:30 +0100] LoadPPDs: Read "/etc/cups/ppds.dat", 2348 PPDs...
I [27/Jan/2005:10:17:30 +0100] LoadPPDs: No new or changed PPDs...
I [27/Jan/2005:10:17:30 +0100] Full reload complete.
I [27/Jan/2005:10:17:53 +0100] Scheduler shutting down normally.
I [27/Jan/2005:10:17:54 +0100] Listening to 7f000001:631
I [27/Jan/2005:10:17:54 +0100] Polling a170202:631
I [27/Jan/2005:10:17:54 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [27/Jan/2005:10:17:54 +0100] Configured for up to 100 clients.
I [27/Jan/2005:10:17:54 +0100] Allowing up to 100 client connections per host.
I [27/Jan/2005:10:17:54 +0100] Full reload is required.
I [27/Jan/2005:10:17:54 +0100] LoadPPDs: Read "/etc/cups/ppds.dat", 2348 PPDs...
I [27/Jan/2005:10:17:54 +0100] LoadPPDs: No new or changed PPDs...
I [27/Jan/2005:10:17:54 +0100] Full reload complete.
I [27/Jan/2005:10:17:54 +0100] Added remote printer "hp-laserjet-2300d"...
I [27/Jan/2005:10:23:02 +0100] Scheduler shutting down normally.
I [27/Jan/2005:10:23:03 +0100] Listening to 7f000001:631
I [27/Jan/2005:10:23:03 +0100] Polling a170202:631
I [27/Jan/2005:10:23:03 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [27/Jan/2005:10:23:03 +0100] Configured for up to 100 clients.
I [27/Jan/2005:10:23:03 +0100] Allowing up to 100 client connections per host.
I [27/Jan/2005:10:23:03 +0100] Full reload is required.
I [27/Jan/2005:10:23:03 +0100] LoadPPDs: Read "/etc/cups/ppds.dat", 2348 PPDs...
I [27/Jan/2005:10:23:03 +0100] LoadPPDs: No new or changed PPDs...
I [27/Jan/2005:10:23:03 +0100] Full reload complete.
I [27/Jan/2005:10:25:50 +0100] Scheduler shutting down normally.
I [27/Jan/2005:10:25:51 +0100] Listening to 7f000001:631
I [27/Jan/2005:10:25:51 +0100] Polling a170202:631
I [27/Jan/2005:10:25:51 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [27/Jan/2005:10:25:51 +0100] Configured for up to 100 clients.
I [27/Jan/2005:10:25:51 +0100] Allowing up to 100 client connections per host.
I [27/Jan/2005:10:25:51 +0100] Full reload is required.
I [27/Jan/2005:10:25:51 +0100] LoadPPDs: Read "/etc/cups/ppds.dat", 2348 PPDs...
I [27/Jan/2005:10:25:51 +0100] LoadPPDs: No new or changed PPDs...
I [27/Jan/2005:10:25:51 +0100] Full reload complete.
I [27/Jan/2005:10:25:51 +0100] Added remote printer "hp-laserjet-2300d"...
I [27/Jan/2005:13:49:31 +0100] Scheduler shutting down normally.
I [27/Jan/2005:13:49:33 +0100] Listening to 7f000001:631
I [27/Jan/2005:13:49:33 +0100] Polling a170202:631
D [27/Jan/2005:13:49:33 +0100] AddLocation: added location '/'
D [27/Jan/2005:13:49:33 +0100] DenyIP: / deny 00000000/00000000
D [27/Jan/2005:13:49:33 +0100] AllowIP: / allow 7f000001/ffffffff
D [27/Jan/2005:13:49:33 +0100] AddLocation: added location '/jobs'
D [27/Jan/2005:13:49:33 +0100] AddLocation: added location '/admin'
D [27/Jan/2005:13:49:33 +0100] DenyIP: /admin deny 00000000/00000000
D [27/Jan/2005:13:49:33 +0100] AllowIP: /admin allow 7f000001/ffffffff
I [27/Jan/2005:13:49:33 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [27/Jan/2005:13:49:33 +0100] Configured for up to 100 clients.
I [27/Jan/2005:13:49:33 +0100] Allowing up to 100 client connections per host.
I [27/Jan/2005:13:49:33 +0100] Full reload is required.
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "parallel:/dev/lp0"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "ipp"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "lpd"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "socket"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "usb:/dev/usb/lp0"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "usb:/dev/usb/lp1"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "usb:/dev/usb/lp2"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "usb:/dev/usb/lp3"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "usb:/dev/usb/lp4"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "usb:/dev/usb/lp5"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "usb:/dev/usb/lp6"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "usb:/dev/usb/lp7"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "usb:/dev/usb/lp8"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "usb:/dev/usb/lp9"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "usb:/dev/usb/lp10"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "usb:/dev/usb/lp11"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "usb:/dev/usb/lp12"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "usb:/dev/usb/lp13"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "usb:/dev/usb/lp14"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "usb:/dev/usb/lp15"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "http"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "epson:/dev/lp0"...
D [27/Jan/2005:13:49:33 +0100] LoadDevices: Added device "canon:/dev/lp0"...
D [27/Jan/2005:13:49:34 +0100] LoadDevices: Added device "smb"...
I [27/Jan/2005:13:49:34 +0100] LoadPPDs: Read "/etc/cups/ppds.dat", 2348 PPDs...
I [27/Jan/2005:13:49:34 +0100] LoadPPDs: No new or changed PPDs...
D [27/Jan/2005:13:49:34 +0100] LoadAllJobs: Scanning /var/spool/cups...
I [27/Jan/2005:13:49:34 +0100] Full reload complete.
D [27/Jan/2005:13:49:34 +0100] StartListening: NumListeners=1
D [27/Jan/2005:13:49:34 +0100] StartListening: address=7f000001 port=631
D [27/Jan/2005:13:49:34 +0100] ResumeListening: setting input bits...
D [27/Jan/2005:13:49:34 +0100] StartPolling: Started polling daemon for
10.23.2.2:631, pid = 8393
I [27/Jan/2005:13:50:13 +0100] Scheduler shutting down normally.
D [27/Jan/2005:13:50:13 +0100] StopListening: closing all listen sockets.
D [27/Jan/2005:13:50:13 +0100] PauseListening: clearing input bits...
I [27/Jan/2005:13:50:31 +0100] Listening to 7f000001:631
I [27/Jan/2005:13:50:31 +0100] Polling a170202:631
D [27/Jan/2005:13:50:31 +0100] AddLocation: added location '/'
D [27/Jan/2005:13:50:31 +0100] DenyIP: / deny 00000000/00000000
D [27/Jan/2005:13:50:31 +0100] AllowIP: / allow 7f000001/ffffffff
D [27/Jan/2005:13:50:31 +0100] AddLocation: added location '/jobs'
D [27/Jan/2005:13:50:31 +0100] AddLocation: added location '/admin'
D [27/Jan/2005:13:50:31 +0100] DenyIP: /admin deny 00000000/00000000
D [27/Jan/2005:13:50:31 +0100] AllowIP: /admin allow 7f000001/ffffffff
I [27/Jan/2005:13:50:31 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [27/Jan/2005:13:50:31 +0100] Configured for up to 100 clients.
I [27/Jan/2005:13:50:31 +0100] Allowing up to 100 client connections per host.
I [27/Jan/2005:13:50:31 +0100] Full reload is required.
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "parallel:/dev/lp0"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "ipp"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "lpd"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "socket"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "usb:/dev/usb/lp0"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "usb:/dev/usb/lp1"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "usb:/dev/usb/lp2"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "usb:/dev/usb/lp3"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "usb:/dev/usb/lp4"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "usb:/dev/usb/lp5"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "usb:/dev/usb/lp6"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "usb:/dev/usb/lp7"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "usb:/dev/usb/lp8"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "usb:/dev/usb/lp9"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "usb:/dev/usb/lp10"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "usb:/dev/usb/lp11"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "usb:/dev/usb/lp12"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "usb:/dev/usb/lp13"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "usb:/dev/usb/lp14"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "usb:/dev/usb/lp15"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "http"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "epson:/dev/lp0"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "canon:/dev/lp0"...
D [27/Jan/2005:13:50:31 +0100] LoadDevices: Added device "smb"...
I [27/Jan/2005:13:50:31 +0100] LoadPPDs: Read "/etc/cups/ppds.dat", 2348 PPDs...
I [27/Jan/2005:13:50:31 +0100] LoadPPDs: No new or changed PPDs...
D [27/Jan/2005:13:50:31 +0100] LoadAllJobs: Scanning /var/spool/cups...
I [27/Jan/2005:13:50:31 +0100] Full reload complete.
D [27/Jan/2005:13:50:31 +0100] StartListening: NumListeners=1
D [27/Jan/2005:13:50:31 +0100] StartListening: address=7f000001 port=631
D [27/Jan/2005:13:50:31 +0100] ResumeListening: setting input bits...
D [27/Jan/2005:13:50:31 +0100] StartPolling: Started polling daemon for
10.23.2.2:631, pid = 8422
D [27/Jan/2005:13:51:43 +0100] AcceptClient: 5 from localhost:631.
D [27/Jan/2005:13:51:43 +0100] AcceptClient: 6 from localhost:631.
D [27/Jan/2005:13:51:43 +0100] ReadClient: 6 POST / HTTP/1.1
D [27/Jan/2005:13:51:43 +0100] ProcessIPPRequest: 6 status_code=1
D [27/Jan/2005:13:51:43 +0100] ReadClient: 6 POST / HTTP/1.1
D [27/Jan/2005:13:51:43 +0100] ProcessIPPRequest: 6 status_code=1
D [27/Jan/2005:13:51:43 +0100] ReadClient: 6 POST / HTTP/1.1
D [27/Jan/2005:13:51:43 +0100] ProcessIPPRequest: 6 status_code=406
D [27/Jan/2005:13:51:43 +0100] ReadClient: 5 POST / HTTP/1.1
D [27/Jan/2005:13:51:43 +0100] ProcessIPPRequest: 5 status_code=0
D [27/Jan/2005:13:51:43 +0100] CloseClient: 6
D [27/Jan/2005:13:51:43 +0100] CloseClient: 5
D [27/Jan/2005:13:51:46 +0100] AcceptClient: 5 from localhost:631.
D [27/Jan/2005:13:51:46 +0100] ReadClient: 5 POST / HTTP/1.1
D [27/Jan/2005:13:51:46 +0100] ProcessIPPRequest: 5 status_code=1
D [27/Jan/2005:13:51:46 +0100] ReadClient: 5 POST / HTTP/1.1
D [27/Jan/2005:13:51:46 +0100] ProcessIPPRequest: 5 status_code=1
D [27/Jan/2005:13:51:47 +0100] ReadClient: 5 POST / HTTP/1.1
D [27/Jan/2005:13:51:47 +0100] ProcessIPPRequest: 5 status_code=406
D [27/Jan/2005:13:51:47 +0100] ReadClient: 5 POST / HTTP/1.1
D [27/Jan/2005:13:51:47 +0100] ProcessIPPRequest: 5 status_code=1
D [27/Jan/2005:13:51:47 +0100] CloseClient: 5
D [27/Jan/2005:13:51:58 +0100] AcceptClient: 5 from localhost:631.
D [27/Jan/2005:13:51:58 +0100] ReadClient: 5 POST / HTTP/1.1
D [27/Jan/2005:13:51:58 +0100] ProcessIPPRequest: 5 status_code=1
D [27/Jan/2005:13:51:58 +0100] ReadClient: 5 POST / HTTP/1.1
D [27/Jan/2005:13:51:58 +0100] ProcessIPPRequest: 5 status_code=1
D [27/Jan/2005:13:51:58 +0100] ReadClient: 5 POST / HTTP/1.1
D [27/Jan/2005:13:51:58 +0100] ProcessIPPRequest: 5 status_code=406
D [27/Jan/2005:13:51:58 +0100] ReadClient: 5 POST / HTTP/1.1
D [27/Jan/2005:13:51:58 +0100] ProcessIPPRequest: 5 status_code=1
D [27/Jan/2005:13:51:58 +0100] CloseClient: 5
D [27/Jan/2005:13:52:27 +0100] AcceptClient: 5 from localhost:631.
D [27/Jan/2005:13:52:27 +0100] ReadClient: 5 GET / HTTP/1.1
D [27/Jan/2005:13:52:27 +0100] SendFile: 5 file=6
D [27/Jan/2005:13:52:28 +0100] AcceptClient: 8 from localhost:631.
D [27/Jan/2005:13:52:28 +0100] ReadClient: 5 GET /cups.css HTTP/1.1
D [27/Jan/2005:13:52:28 +0100] SendFile: 5 file=6
D [27/Jan/2005:13:52:28 +0100] ReadClient: 8 GET /favicon.ico HTTP/1.1
D [27/Jan/2005:13:52:28 +0100] SendError: 8 code=404 (Not Found)
D [27/Jan/2005:13:52:28 +0100] CloseClient: 8
D [27/Jan/2005:13:52:29 +0100] ReadClient: 5 GET /images/navbar.gif HTTP/1.1
D [27/Jan/2005:13:52:29 +0100] SendFile: 5 file=6
D [27/Jan/2005:13:52:31 +0100] ReadClient: 5 GET /printers HTTP/1.1
D [27/Jan/2005:13:52:31 +0100] CGI /usr/lib/cups/cgi-bin/printers.cgi started -
PID = 8464
I [27/Jan/2005:13:52:31 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi"
(pid=8464)
D [27/Jan/2005:13:52:31 +0100] SendCommand: 5 file=8
D [27/Jan/2005:13:52:31 +0100] AcceptClient: 6 from localhost:631.
D [27/Jan/2005:13:52:32 +0100] ReadClient: 6 POST / HTTP/1.1
D [27/Jan/2005:13:52:32 +0100] ProcessIPPRequest: 6 status_code=406
D [27/Jan/2005:13:52:32 +0100] ReadClient: 6 POST / HTTP/1.1
D [27/Jan/2005:13:52:32 +0100] ProcessIPPRequest: 6 status_code=1
D [27/Jan/2005:13:52:32 +0100] AcceptClient: 9 from localhost:631.
D [27/Jan/2005:13:52:32 +0100] CloseClient: 6
D [27/Jan/2005:13:52:32 +0100] ReadClient: 9 GET /images/add-printer.gif
HTTP/1.1
D [27/Jan/2005:13:52:32 +0100] SendFile: 9 file=6
I [27/Jan/2005:13:52:51 +0100] Scheduler shutting down normally.
D [27/Jan/2005:13:52:51 +0100] CloseClient: 5
D [27/Jan/2005:13:52:51 +0100] CloseClient: 9
D [27/Jan/2005:13:52:51 +0100] StopListening: closing all listen sockets.
D [27/Jan/2005:13:52:51 +0100] PauseListening: clearing input bits...
