[cups.general] cups hangs when restarting after log rotation

Tue Jul 20 06:37:43 PDT 2004

I'm having a problem with cups hanging when restarting after I perform my
weekly log rotation which runs through the cron.daily job.  It shuts down
fine but when it's time to start again, it hangs when detecting a device.
All of my servers are running Red Hat enterprise 3 with cups-1.1.17-13.3.6
but only one of them is having this problem so I upgraded this particular
server to cups-1.1.20-2 hoping it might help but I am still having the same
issue.  My other servers still run 1.1.17-13.3.6 and start fine after the
log rotate. 

When I come into work on Monday (the problem only arises on Monday at 2AM),
I can't access http://localhost:631 <http://localhost:631/>  and I have this
result when I do "ps -aux | grep cups":

root     23577  0.0  0.0  2128  884  ?       S    Jul18   0:00 /bin/sh
/tmp/logrotate.yAIOup /var/log/cups/*_log

root     23578  0.0  0.1  2144 1088 ?       S    Jul18   0:00 /bin/sh
/etc/init.d/cups condrestart

root     23592  0.0  0.0  1520  492  ?       S    Jul18   0:00 initlog -q -c
cupsd

root     23593  0.0  0.0  4224  992  ?       S    Jul18   0:00 cupsd

root     23594  0.0  0.1  5724 1896 ?       S    Jul18   0:00 cupsd

root     23602  0.0  0.0  3620  860  ?       S    Jul18   0:00
/usr/lib/cups/backend/serial

root     29873  0.0  0.0  1612  492  pts/0 S    15:27   0:00 grep cups

When I kill "/usr/lib/cups/backend/serial", cups will continue loading
perfectly.

Below is the cups error log.  Notice that it stops loading at line
"LoadDevices: Backend did not respond within 30 seconds!".   Notice also in
the following log that I killed the process specified above at 15:27:14)

I [18/Jul/2004:04:02:03 -0400] Listening to 0:631

D [18/Jul/2004:04:02:03 -0400] AddLocation: added location '/admin'

D [18/Jul/2004:04:02:03 -0400] DenyIP: /admin deny IP/MASK

D [18/Jul/2004:04:02:03 -0400] AllowIP: /admin allow IP/MASK

D [18/Jul/2004:04:02:03 -0400] AddLocation: added location '/'

D [18/Jul/2004:04:02:03 -0400] DenyIP: / deny IP/MASK

D [18/Jul/2004:04:02:03 -0400] AllowIP: / allow IP/MASK

I [18/Jul/2004:04:02:03 -0400] Loaded configuration file
"/etc/cups/cupsd.conf"

I [18/Jul/2004:04:02:03 -0400] Configured for up to 100 clients.

I [18/Jul/2004:04:02:03 -0400] Allowing up to 100 client connections per
host.

I [18/Jul/2004:04:02:03 -0400] Full reload is required.

D [18/Jul/2004:04:02:03 -0400] LoadAllPrinters: Loading printer
PRINTERNAME...

#.more printers loaded.

D [18/Jul/2004:04:02:03 -0400] LoadDevices: Added device "http"...

D [18/Jul/2004:04:02:03 -0400] LoadDevices: Added device "ipp"...

D [18/Jul/2004:04:02:03 -0400] LoadDevices: Added device "lpd"...

D [18/Jul/2004:04:02:03 -0400] LoadDevices: Added device "smb"...

D [18/Jul/2004:04:02:03 -0400] LoadDevices: Added device "scsi"...

W [18/Jul/2004:04:02:33 -0400] LoadDevices: Backend did not respond within
30 seconds!

D [19/Jul/2004:15:27:14 -0400] LoadDevices: Added device "socket"...

D [19/Jul/2004:15:27:14 -0400] LoadDevices: Added device
"usb:/dev/usb/lp0"...

D [19/Jul/2004:15:27:14 -0400] LoadDevices: Added device
"usb:/dev/usb/lp1"...

D [19/Jul/2004:15:27:14 -0400] LoadDevices: Added device
"usb:/dev/usb/lp2"...

#.more usb devices added.

I [19/Jul/2004:15:27:14 -0400] LoadPPDs: Read "/etc/cups/ppds.dat", 2000
PPDs...

I [19/Jul/2004:15:27:14 -0400] LoadPPDs: No new or changed PPDs...

D [19/Jul/2004:15:27:14 -0400] LoadAllJobs: Scanning /var/spool/cups...

D [19/Jul/2004:15:27:14 -0400] LoadAllJobs: Loading attributes for job 1...

D [19/Jul/2004:15:27:14 -0400] LoadAllJobs: Loading attributes for job 2...

#.loading other jobs.

I [19/Jul/2004:15:27:14 -0400] Full reload complete.

D [19/Jul/2004:15:27:14 -0400] StartListening: NumListeners=1

D [19/Jul/2004:15:27:14 -0400] StartListening: address=00000000 port=631

D [19/Jul/2004:15:27:14 -0400] ResumeListening: setting input bits...

#...and now it starts printing.

I googled "LoadDevices: Backend did not respond within 30 seconds!"  but
that didn't help me much.

I don't know what is causing this since the cupsd.conf files are exactly the
same on all servers except for the DENY/ALLOW IPs in "Location /" which vary
from one server to the next but I don't think that would have any impact on
cups restarting...  Any ideas as to what is causing this or how to fix it
would be greatly appreciated.  It's not a big deal but I've been having this
problem for weeks now and I can't figure out why so I thought I'd put a post
to see if I could get more help.

Thanks,

F-J Poitras

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.cups.org/pipermail/cups/attachments/20040720/1289515a/attachment-0001.html>