Bug#758284: cups-daemon: IdleExitTimeout is unreliable
Package: cups-daemon
Version: 1.7.4-4
Severity: normal
This machine had a minimal Wheezy and has been updated to testing.
systems-sysv was installed; rebooted and then cups installed without its
Recommends:, A print queue was established and a job sent to the printer.
I=IdleExitTimeout
t=time for cups to become inactive after cups reports 'Job completed'.
For I > 30s, t=I+30.
For I < 30s, t=I.
For I = 30s it appears to be a toss up whether it is I or I+30.
An extract from an error_log for I=50s:
I [16/Aug/2014:09:57:54 +0100] [Job 3] Job completed.
D [16/Aug/2014:09:57:54 +0100] cupsdMarkDirty(---J-)
D [16/Aug/2014:09:57:54 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [16/Aug/2014:09:57:54 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [16/Aug/2014:09:57:54 +0100] Discarding unused printer-state-changed event...
D [16/Aug/2014:09:57:54 +0100] cupsdMarkDirty(---J-)
D [16/Aug/2014:09:57:54 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [16/Aug/2014:09:57:54 +0100] cupsdMarkDirty(---J-)
D [16/Aug/2014:09:57:54 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [16/Aug/2014:09:57:54 +0100] cupsd is not idle any more, canceling shutdown.
D [16/Aug/2014:09:57:56 +0100] [Job 3] Unloading...
* D [16/Aug/2014:09:57:56 +0100] cupsd is not idle any more, canceling shutdown.
* I [16/Aug/2014:09:58:25 +0100] Saving job.cache...
D [16/Aug/2014:09:58:25 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Printing jobs and dirty files"
D [16/Aug/2014:09:58:25 +0100] cupsd is idle, scheduling shutdown in 50 seconds.
I [16/Aug/2014:09:59:15 +0100] Printer sharing is off and there are no jobs pending, shutting down for now.
I [16/Aug/2014:09:59:15 +0100] Scheduler shutting down normally.
D [16/Aug/2014:09:59:15 +0100] Discarding unused server-stopped event...
I [16/Aug/2014:09:59:15 +0100] Saving job.cache...
D [16/Aug/2014:09:59:15 +0100] cupsdStopSelect()
Please note the almost 30s delay at the lines marked *.
An extract from an error_log for I=20s:
I [16/Aug/2014:10:06:24 +0100] [Job 4] Job completed.
D [16/Aug/2014:10:06:24 +0100] cupsdMarkDirty(---J-)
D [16/Aug/2014:10:06:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [16/Aug/2014:10:06:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [16/Aug/2014:10:06:24 +0100] Discarding unused printer-state-changed event...
D [16/Aug/2014:10:06:24 +0100] cupsdMarkDirty(---J-)
D [16/Aug/2014:10:06:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [16/Aug/2014:10:06:24 +0100] cupsdMarkDirty(---J-)
D [16/Aug/2014:10:06:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [16/Aug/2014:10:06:24 +0100] cupsd is not idle any more, canceling shutdown.
D [16/Aug/2014:10:06:25 +0100] [Job 4] Unloading...
D [16/Aug/2014:10:06:25 +0100] cupsd is idle, scheduling shutdown in 20 seconds.
I [16/Aug/2014:10:06:45 +0100] Printer sharing is off and there are no jobs pending, shutting down for now.
I [16/Aug/2014:10:06:45 +0100] Scheduler shutting down normally.
D [16/Aug/2014:10:06:45 +0100] Discarding unused server-stopped event...
I [16/Aug/2014:10:06:45 +0100] Saving job.cache...
D [16/Aug/2014:10:06:45 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Printing jobs and dirty files"
I [16/Aug/2014:10:06:45 +0100] Saving job.cache...
D [16/Aug/2014:10:06:45 +0100] cupsdStopSelect()
Please note the lack of a 30s delay.
Regards,
Brian.
Reply to: