id,summary,reporter,owner,description,type,status,priority,milestone,component,version,severity,resolution,keywords,cc,mlocked 9712,the backend is not suspending when idle reliably,brian@…,Peter Bennett,"Frequently I find my backend which is configured to suspend when idle, running while there is nothing for it to be doing. Sometimes that will go on for hours and then all of a sudden it decides to suspend. I have been running a test for the last 20-30 minutes, sampling mythshutdown -s and echoing the return to see what the backend thinks it's state is. Here is the result: {{{ $ while sleep 60; do echo -e ""$(date): \c""; mythshutdown -s; echo $?; done Wed Apr 6 07:41:47 EDT 2011: 0 Wed Apr 6 07:42:47 EDT 2011: 0 Wed Apr 6 07:43:48 EDT 2011: 0 Wed Apr 6 07:44:48 EDT 2011: 0 Wed Apr 6 07:45:48 EDT 2011: 0 Wed Apr 6 07:46:48 EDT 2011: 0 Wed Apr 6 07:47:48 EDT 2011: 0 Wed Apr 6 07:48:49 EDT 2011: 0 Wed Apr 6 07:49:49 EDT 2011: 0 Wed Apr 6 07:50:49 EDT 2011: 0 Wed Apr 6 07:51:49 EDT 2011: 0 Wed Apr 6 07:52:50 EDT 2011: 0 Wed Apr 6 07:53:50 EDT 2011: 0 Wed Apr 6 07:54:50 EDT 2011: 0 Wed Apr 6 07:55:50 EDT 2011: 0 Wed Apr 6 07:56:51 EDT 2011: 0 Wed Apr 6 07:57:51 EDT 2011: 0 Wed Apr 6 07:58:51 EDT 2011: 0 Wed Apr 6 07:59:51 EDT 2011: 0 Wed Apr 6 08:00:52 EDT 2011: 0 Wed Apr 6 08:01:52 EDT 2011: 0 Wed Apr 6 08:02:52 EDT 2011: 0 Wed Apr 6 08:03:52 EDT 2011: 0 Wed Apr 6 08:04:52 EDT 2011: 0 Wed Apr 6 08:05:53 EDT 2011: 0 Wed Apr 6 08:06:53 EDT 2011: 0 }}} As you an see, the backend certainly thinks it's idle. The backend log for the same period of time: {{{ 2011-04-06 07:41:47.780 MainServer::ANN Monitor 2011-04-06 07:41:47.781 adding: pvr as a client (events: 0) 2011-04-06 07:41:47.782 MainServer::ANN Monitor 2011-04-06 07:41:47.783 adding: pvr as a client (events: 1) 2011-04-06 07:42:48.013 MainServer::ANN Monitor 2011-04-06 07:42:48.014 adding: pvr as a client (events: 0) 2011-04-06 07:42:48.015 MainServer::ANN Monitor 2011-04-06 07:42:48.015 adding: pvr as a client (events: 1) 2011-04-06 07:43:48.248 MainServer::ANN Monitor 2011-04-06 07:43:48.249 adding: pvr as a client (events: 0) 2011-04-06 07:43:48.250 MainServer::ANN Monitor 2011-04-06 07:43:48.250 adding: pvr as a client (events: 1) 2011-04-06 07:44:48.482 MainServer::ANN Monitor 2011-04-06 07:44:48.482 adding: pvr as a client (events: 0) 2011-04-06 07:44:48.483 MainServer::ANN Monitor 2011-04-06 07:44:48.484 adding: pvr as a client (events: 1) 2011-04-06 07:45:48.713 MainServer::ANN Monitor 2011-04-06 07:45:48.714 adding: pvr as a client (events: 0) 2011-04-06 07:45:48.715 MainServer::ANN Monitor 2011-04-06 07:45:48.715 adding: pvr as a client (events: 1) 2011-04-06 07:46:48.945 MainServer::ANN Monitor 2011-04-06 07:46:48.946 adding: pvr as a client (events: 0) 2011-04-06 07:46:48.947 MainServer::ANN Monitor 2011-04-06 07:46:48.948 adding: pvr as a client (events: 1) 2011-04-06 07:47:49.179 MainServer::ANN Monitor 2011-04-06 07:47:49.180 adding: pvr as a client (events: 0) 2011-04-06 07:47:49.181 MainServer::ANN Monitor 2011-04-06 07:47:49.181 adding: pvr as a client (events: 1) 2011-04-06 07:48:49.413 MainServer::ANN Monitor 2011-04-06 07:48:49.413 adding: pvr as a client (events: 0) 2011-04-06 07:48:49.415 MainServer::ANN Monitor 2011-04-06 07:48:49.415 adding: pvr as a client (events: 1) 2011-04-06 07:49:49.645 MainServer::ANN Monitor 2011-04-06 07:49:49.645 adding: pvr as a client (events: 0) 2011-04-06 07:49:49.646 MainServer::ANN Monitor 2011-04-06 07:49:49.647 adding: pvr as a client (events: 1) 2011-04-06 07:50:49.877 MainServer::ANN Monitor 2011-04-06 07:50:49.878 adding: pvr as a client (events: 0) 2011-04-06 07:50:49.879 MainServer::ANN Monitor 2011-04-06 07:50:49.880 adding: pvr as a client (events: 1) 2011-04-06 07:51:07.869 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 07:51:50.111 MainServer::ANN Monitor 2011-04-06 07:51:50.111 adding: pvr as a client (events: 0) 2011-04-06 07:51:50.112 MainServer::ANN Monitor 2011-04-06 07:51:50.113 adding: pvr as a client (events: 1) 2011-04-06 07:52:50.345 MainServer::ANN Monitor 2011-04-06 07:52:50.346 adding: pvr as a client (events: 0) 2011-04-06 07:52:50.347 MainServer::ANN Monitor 2011-04-06 07:52:50.348 adding: pvr as a client (events: 1) 2011-04-06 07:53:50.578 MainServer::ANN Monitor 2011-04-06 07:53:50.579 adding: pvr as a client (events: 0) 2011-04-06 07:53:50.580 MainServer::ANN Monitor 2011-04-06 07:53:50.581 adding: pvr as a client (events: 1) 2011-04-06 07:54:50.813 MainServer::ANN Monitor 2011-04-06 07:54:50.814 adding: pvr as a client (events: 0) 2011-04-06 07:54:50.815 MainServer::ANN Monitor 2011-04-06 07:54:50.815 adding: pvr as a client (events: 1) 2011-04-06 07:55:51.046 MainServer::ANN Monitor 2011-04-06 07:55:51.046 adding: pvr as a client (events: 0) 2011-04-06 07:55:51.048 MainServer::ANN Monitor 2011-04-06 07:55:51.048 adding: pvr as a client (events: 1) 2011-04-06 07:56:51.279 MainServer::ANN Monitor 2011-04-06 07:56:51.279 adding: pvr as a client (events: 0) 2011-04-06 07:56:51.280 MainServer::ANN Monitor 2011-04-06 07:56:51.281 adding: pvr as a client (events: 1) 2011-04-06 07:57:51.513 MainServer::ANN Monitor 2011-04-06 07:57:51.513 adding: pvr as a client (events: 0) 2011-04-06 07:57:51.516 MainServer::ANN Monitor 2011-04-06 07:57:51.517 adding: pvr as a client (events: 1) 2011-04-06 07:58:51.748 MainServer::ANN Monitor 2011-04-06 07:58:51.748 adding: pvr as a client (events: 0) 2011-04-06 07:58:51.749 MainServer::ANN Monitor 2011-04-06 07:58:51.750 adding: pvr as a client (events: 1) 2011-04-06 07:59:51.980 MainServer::ANN Monitor 2011-04-06 07:59:51.980 adding: pvr as a client (events: 0) 2011-04-06 07:59:51.981 MainServer::ANN Monitor 2011-04-06 07:59:51.982 adding: pvr as a client (events: 1) 2011-04-06 08:00:52.213 MainServer::ANN Monitor 2011-04-06 08:00:52.213 adding: pvr as a client (events: 0) 2011-04-06 08:00:52.215 MainServer::ANN Monitor 2011-04-06 08:00:52.215 adding: pvr as a client (events: 1) 2011-04-06 08:01:52.448 MainServer::ANN Monitor 2011-04-06 08:01:52.449 adding: pvr as a client (events: 0) 2011-04-06 08:01:52.450 MainServer::ANN Monitor 2011-04-06 08:01:52.450 adding: pvr as a client (events: 1) 2011-04-06 08:02:52.679 MainServer::ANN Monitor 2011-04-06 08:02:52.680 adding: pvr as a client (events: 0) 2011-04-06 08:02:52.681 MainServer::ANN Monitor 2011-04-06 08:02:52.682 adding: pvr as a client (events: 1) 2011-04-06 08:03:52.912 MainServer::ANN Monitor 2011-04-06 08:03:52.912 adding: pvr as a client (events: 0) 2011-04-06 08:03:52.913 MainServer::ANN Monitor 2011-04-06 08:03:52.914 adding: pvr as a client (events: 1) 2011-04-06 08:04:53.144 MainServer::ANN Monitor 2011-04-06 08:04:53.145 adding: pvr as a client (events: 0) 2011-04-06 082011-04-06 08:05:00.781 UPnpMedia: BuildMediaMap Done. Found 118 objects 2011-04-06 08:05:53.377 MainServer::ANN Monitor 2011-04-06 08:05:53.377 adding: pvr as a client (events: 0) 2011-04-06 08:05:53.379 MainServer::ANN Monitor 2011-04-06 08:05:53.379 adding: pvr as a client (events: 1) 2011-04-06 08:06:08.094 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 08:04:53.146 MainServer::ANN Monitor 2011-04-06 08:04:53.146 adding: pvr as a client (events: 1) 2011-04-06 08:05:00.732 UPnpMedia: BuildMediaMap VIDEO scan starting in :/video/mythvideo: 2011-04-06 08:06:53.613 MainServer::ANN Monitor 2011-04-06 08:06:53.613 adding: pvr as a client (events: 0) 2011-04-06 08:06:53.614 MainServer::ANN Monitor 2011-04-06 08:06:53.615 adding: pvr as a client (events: 1) }}} When it goes for hours without idling and without being polled for it's status like it did all night last night, the backend log looks like: {{{ 2011-04-05 23:15:09.046 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-05 23:17:05.006 MainServer::ANN Monitor 2011-04-05 23:17:05.007 adding: pvr as a client (events: 2) 2011-04-05 23:30:09.168 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-05 23:30:53.748 UPnpMedia: BuildMediaMap VIDEO scan starting in :/video/mythvideo: 2011-04-05 23:30:53.863 UPnpMedia: BuildMediaMap Done. Found 118 objects 2011-04-05 23:45:09.446 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 00:00:09.723 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 00:00:58.874 UPnpMedia: BuildMediaMap VIDEO scan starting in :/video/mythvideo: 2011-04-06 00:00:58.924 UPnpMedia: BuildMediaMap Done. Found 118 objects 2011-04-06 00:15:10.000 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 00:17:02.904 MainServer::ANN Monitor 2011-04-06 00:17:02.905 adding: pvr as a client (events: 2) 2011-04-06 00:30:10.286 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 00:30:59.935 UPnpMedia: BuildMediaMap VIDEO scan starting in :/video/mythvideo: 2011-04-06 00:30:59.999 UPnpMedia: BuildMediaMap Done. Found 118 objects 2011-04-06 00:45:10.575 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 01:00:10.861 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 01:01:05.010 UPnpMedia: BuildMediaMap VIDEO scan starting in :/video/mythvideo: 2011-04-06 01:01:05.059 UPnpMedia: BuildMediaMap Done. Found 118 objects 2011-04-06 01:15:11.146 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 01:17:01.755 MainServer::ANN Monitor 2011-04-06 01:17:01.756 adding: pvr as a client (events: 2) 2011-04-06 01:30:11.426 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 01:31:07.070 UPnpMedia: BuildMediaMap VIDEO scan starting in :/video/mythvideo: 2011-04-06 01:31:07.119 UPnpMedia: BuildMediaMap Done. Found 118 objects 2011-04-06 01:45:11.711 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 02:00:11.994 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 02:01:11.130 UPnpMedia: BuildMediaMap VIDEO scan starting in :/video/mythvideo: 2011-04-06 02:01:11.180 UPnpMedia: BuildMediaMap Done. Found 118 objects 2011-04-06 02:15:12.277 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 02:17:02.299 MainServer::ANN Monitor 2011-04-06 02:17:02.299 adding: pvr as a client (events: 2) 2011-04-06 02:30:12.558 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 02:31:18.192 UPnpMedia: BuildMediaMap VIDEO scan starting in :/video/mythvideo: 2011-04-06 02:31:18.242 UPnpMedia: BuildMediaMap Done. Found 118 objects 2011-04-06 02:45:12.841 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 03:00:13.119 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 03:01:20.253 UPnpMedia: BuildMediaMap VIDEO scan starting in :/video/mythvideo: 2011-04-06 03:01:20.303 UPnpMedia: BuildMediaMap Done. Found 118 objects 2011-04-06 03:15:13.402 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 03:17:01.844 MainServer::ANN Monitor 2011-04-06 03:17:01.845 adding: pvr as a client (events: 2) 2011-04-06 03:30:13.685 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 03:31:24.313 UPnpMedia: BuildMediaMap VIDEO scan starting in :/video/mythvideo: 2011-04-06 03:31:24.363 UPnpMedia: BuildMediaMap Done. Found 118 objects 2011-04-06 03:45:13.969 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 04:00:14.252 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 04:01:26.374 UPnpMedia: BuildMediaMap VIDEO scan starting in :/video/mythvideo: 2011-04-06 04:01:26.423 UPnpMedia: BuildMediaMap Done. Found 118 objects 2011-04-06 04:15:14.535 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 04:17:02.388 MainServer::ANN Monitor 2011-04-06 04:17:02.389 adding: pvr as a client (events: 2) 2011-04-06 04:26:19.938 mythbackend: Last message repeated 88 times: Running housekeeping thread 2011-04-06 04:26:19.942 mythbackend: Running mythfilldatabase 2011-04-06 04:26:19.942 Running mythfilldatabase 2011-04-06 04:30:14.820 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 04:31:24.027 mythfilldatabase still running, skipping checks. 2011-04-06 04:31:31.434 UPnpMedia: BuildMediaMap VIDEO scan starting in :/video/mythvideo: 2011-04-06 04:31:31.500 UPnpMedia: BuildMediaMap Done. Found 118 objects 2011-04-06 04:36:30.031 mythfilldatabase still running, skipping checks. 2011-04-06 04:40:56.775 MainServer::ANN Playback 2011-04-06 04:40:56.776 adding: pvr as a client (events: 0) 2011-04-06 04:40:56.777 MainServer::ANN Monitor 2011-04-06 04:40:56.777 adding: pvr as a client (events: 1) 2011-04-06 04:40:56.821 Reschedule requested for id -1. 2011-04-06 04:41:18.684 Scheduled 750 items in 21.7 = 0.89 match + 20.85 place 2011-04-06 04:41:18.756 scheduler: Scheduled items: Scheduled 750 items in 21.7 = 0.89 match + 20.85 place 2011-04-06 04:45:15.153 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 05:00:15.460 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 05:01:31.521 UPnpMedia: BuildMediaMap VIDEO scan starting in :/video/mythvideo: 2011-04-06 05:01:31.572 UPnpMedia: BuildMediaMap Done. Found 118 objects 2011-04-06 05:15:15.755 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 05:17:02.106 MainServer::ANN Monitor 2011-04-06 05:17:02.107 adding: pvr as a client (events: 2) 2011-04-06 05:30:16.050 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 05:31:35.583 UPnpMedia: BuildMediaMap VIDEO scan starting in :/video/mythvideo: 2011-04-06 05:31:35.632 UPnpMedia: BuildMediaMap Done. Found 118 objects 2011-04-06 05:45:16.347 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 06:00:16.642 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 06:00:54.681 MainServer::ANN Playback 2011-04-06 06:00:54.682 adding: jenny as a client (events: 0) 2011-04-06 06:00:54.685 MainServer::ANN Monitor 2011-04-06 06:00:54.686 adding: jenny as a client (events: 1) 2011-04-06 06:01:40.642 UPnpMedia: BuildMediaMap VIDEO scan starting in :/video/mythvideo: 2011-04-06 06:01:40.693 UPnpMedia: BuildMediaMap Done. Found 118 objects 2011-04-06 06:15:16.937 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 06:17:01.655 MainServer::ANN Monitor 2011-04-06 06:17:01.656 adding: pvr as a client (events: 2) 2011-04-06 06:30:17.229 AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2011-04-06 06:31:45.703 UPnpMedia: BuildMediaMap VIDEO scan starting in :/video/mythvideo: 2011-04-06 06:31:45.752 UPnpMedia: BuildMediaMap Done. Found 118 objects }}} As you can see, all night it ran and did no appreciable work other than the mythfilldatabase at 4:26.",Bug Report - General,closed,minor,unknown,MythTV - Mythwelcome & Mythshutdown,0.24-fixes,medium,Fixed,,,0