Duncan Webb wrote: > John Molohan wrote: > >> Duncan Webb wrote: >> >>> John Molohan wrote: >>> >>> >>>> 2007-11-08 21:24:28,198 DEBUG autoshutdown.py (773): Executing >>>> syscall: ps -eo cmd | egrep -v "grep" | egrep >>>> "(/|[[:space:]]|^)(urpm|urpmi|transcode|cdrecord|mplayer|xine|mencoder)($|[[:space:]])" >>>> 2007-11-08 21:24:28,241 DEBUG autoshutdown.py (756): no external >>>> process(es) running >>>> 2007-11-08 21:24:28,243 DEBUG autoshutdown.py (385): Shutdown issued >>>> by autoshutdown timer! >>>> 2007-11-08 21:24:28,244 DEBUG autoshutdown.py (492): shutdown wakeup >>>> 2007-11-08 21:24:31,801 DEBUG autoshutdown.py (454): No more >>>> recordings available >>>> 2007-11-08 21:24:31,802 DEBUG autoshutdown.py (732): Default wakeup >>>> at Fri Nov 9 13:00:00 2007 >>>> 2007-11-08 21:24:31,803 DEBUG autoshutdown.py (478): Picked wakeup at >>>> Fri Nov 9 13:00:00 2007 >>>> 2007-11-08 21:24:31,804 DEBUG autoshutdown.py (549): Wakeup-command >>>> /usr/local/bin/set_acpi.sh "2007-11-09 12:57" >>>> 2007-11-08 21:24:31,805 DEBUG autoshutdown.py (773): Executing >>>> syscall: /usr/local/bin/set_acpi.sh "2007-11-09 12:57" >>>> 2007-11-08 21:24:32,856 DEBUG autoshutdown.py (502): shutdown system >>>> 2007-11-08 21:24:33,895 ERROR removed timer 1 >>>> Traceback (most recent call last): >>>> File >>>> "/usr/lib/python2.5/site-packages/kaa/notifier/pynotifier/nf_generic.py", >>>> line 153, in step >>>> if not timer[ CALLBACK ](): >>>> File "/usr/lib/python2.5/site-packages/kaa/notifier/timer.py", line >>>> 90, in __call__ >>>> return super(Timer, self).__call__(*args, **kwargs) >>>> File "/usr/lib/python2.5/site-packages/kaa/notifier/nf_wrapper.py", >>>> line 96, in __call__ >>>> ret = super(NotifierCallback, self).__call__(*args, **kwargs) >>>> File "/usr/lib/python2.5/site-packages/kaa/notifier/callback.py", line >>>> 168, in __call__ >>>> result = cb(*cb_args, **cb_kwargs) >>>> File "/usr/lib/python2.5/site-packages/freevo/rc.py", line 624, in poll >>>> c[0](*c[4]) >>>> File "/usr/lib/python2.5/site-packages/freevo/plugin.py", line 155, in >>>> poll_wrapper >>>> self.real_poll() >>>> File >>>> "/usr/lib/python2.5/site-packages/freevo/plugins/autoshutdown.py", line >>>> 386, in poll >>>> shutdown_action(action=Shutdown.SHUTDOWN_WAKEUP) >>>> File >>>> "/usr/lib/python2.5/site-packages/freevo/plugins/autoshutdown.py", line >>>> 503, in shutdown_action >>>> __cleanup_freevo() >>>> File >>>> "/usr/lib/python2.5/site-packages/freevo/plugins/autoshutdown.py", line >>>> 619, in __cleanup_freevo >>>> plugin.shutdown() >>>> File "/usr/lib/python2.5/site-packages/freevo/plugin.py", line 352, in >>>> shutdown >>>> p.shutdown() >>>> File "/usr/lib/python2.5/site-packages/freevo/www/__init__.py", line >>>> 57, in shutdown >>>> print 'Stopping webserver plugin.' >>>> File "/usr/lib/python2.5/site-packages/freevo/config.py", line 129, in >>>> write >>>> sys.__stdout__.flush() >>>> IOError: [Errno 5] Input/output error >>>> >>>> >>> This is a bit strange, looks as if stdout is already closed. What >>> happens when the webserver is not started as a plug-in but as a daemon. >>> This could, but I suspect doesn't, have anything to do with the >>> kaa.notifier changes. >>> >>> Duncan >>> >>> >>> >> Hi Duncan, >> >> I haven't been able to reproduce that error yet but got a different one >> on shutdown tonight. Freevo had been started by freevo -fs & from a ssh >> session and this was on the console: >> >> Unhandled exception in thread started by <bound method >> Identify_Thread.__bootstrap of <Identify_Thread(Thread-2, stopped daemon)>> >> Traceback (most recent call last): >> File "/usr/lib/python2.5/threading.py", line 482, in __bootstrap >> " (most likely raised during interpreter shutdown):") >> File "/usr/lib/python2.5/site-packages/freevo/config.py", line 124, in >> write >> lock.acquire() >> AttributeError: 'NoneType' object has no attribute 'acquire' >> > > This is a strange error from config as lock is a global object that is > set at the beginning, the idea is to ensure that debug messages don't > overlap from the main thread and other threads. > > Just in case I've added a test for lock. > > Duncan > > > >> Freevo shut down but the PC didn't power off as it should. As mentioned >> in the feature request I think this could be due to the audioscrobbler >> plugin, I had disabled it over the last few days and had no problem. I >> re-enabled it today. /var/log/freevo/main-0.log shows: >> >> 2007-11-14 21:04:08,500 DEBUG childapp.py (171): sending >> "SeekRelative+15" to pid 19250 >> 2007-11-14 21:56:13,105 DEBUG childapp.py (442): stderr: no data, >> closing log >> 2007-11-14 21:56:13,600 DEBUG xine.py (135): '' possible >> 2007-11-14 21:56:13,604 DEBUG mplayer.py (98): u'dvd://' possible >> 2007-11-14 21:56:13,605 DEBUG xine.py (123): u'dvd://' good >> 2007-11-14 22:31:32,914 DEBUG autoshutdown.py (673): Scheduled >> recording 0 at Thu Nov 15 03:45:00 2007 is Thu 2007-11-15 03:45->04:45 >> (03:45) south-east.bbc1.bbc.co.uk Sign Zone:Michael Palin's New Europe >> 2007-11-14 22:31:32,915 DEBUG autoshutdown.py (773): Executing >> syscall: ps -eo cmd | egrep -v "grep" | egrep >> "(/|[[:space:]]|^)(urpm|urpmi|transcode|cdrecord|mplayer|xine|mencoder)($|[[:space:]])" >> 2007-11-14 22:31:33,073 DEBUG autoshutdown.py (756): no external >> process(es) running >> 2007-11-14 22:31:33,074 DEBUG autoshutdown.py (385): Shutdown issued >> by autoshutdown timer! >> 2007-11-14 22:31:33,075 DEBUG autoshutdown.py (492): shutdown wakeup >> 2007-11-14 22:31:43,463 DEBUG autoshutdown.py (673): Scheduled >> recording 0 at Thu Nov 15 03:45:00 2007 is Thu 2007-11-15 03:45->04:45 >> (03:45) south-east.bbc1.bbc.co.uk Sign Zone:Michael Palin's New Europe >> 2007-11-14 22:31:43,465 DEBUG autoshutdown.py (732): Default wakeup >> at Thu Nov 15 13:00:00 2007 >> 2007-11-14 22:31:43,466 DEBUG autoshutdown.py (549): Wakeup-command >> /usr/local/bin/set_acpi.sh "2007-11-15 03:41" >> 2007-11-14 22:31:43,467 DEBUG autoshutdown.py (773): Executing >> syscall: /usr/local/bin/set_acpi.sh "2007-11-15 03:41" >> 2007-11-14 22:31:44,523 DEBUG autoshutdown.py (502): shutdown system >> AudioScrobbler plugin: I have shut down >> >> A successful shutdown looks like this: >> >> 2007-11-14 16:44:58,858 DEBUG autoshutdown.py (756): no external >> process(es) running >> 2007-11-14 16:44:58,860 DEBUG autoshutdown.py (385): Shutdown issued >> by autoshutdown timer! >> 2007-11-14 16:44:58,861 DEBUG autoshutdown.py (492): shutdown wakeup >> 2007-11-14 16:45:09,749 DEBUG autoshutdown.py (673): Scheduled >> recording 0 at Thu Nov 15 03:45:00 2007 is Thu 2007-11-15 03:45->04:45 >> (03:45) south-east.bbc1.bbc.co.uk Sign Zone:Michael Palin's New Europe >> 2007-11-14 16:45:09,751 DEBUG autoshutdown.py (732): Default wakeup >> at Thu Nov 15 13:00:00 2007 >> 2007-11-14 16:45:09,752 DEBUG autoshutdown.py (549): Wakeup-command >> /usr/local/bin/set_acpi.sh "2007-11-15 03:41" >> 2007-11-14 16:45:09,753 DEBUG autoshutdown.py (773): Executing >> syscall: /usr/local/bin/set_acpi.sh "2007-11-15 03:41" >> 2007-11-14 16:45:10,793 DEBUG autoshutdown.py (502): shutdown system >> 2007-11-14 16:45:11,983 DEBUG autoshutdown.py (773): Executing >> syscall: shutdown -h now >> >> So on the unsuccessful one the last line: >> 2007-11-14 16:45:11,983 DEBUG autoshutdown.py (773): Executing >> syscall: shutdown -h now >> is missing and instead we see: >> AudioScrobbler plugin: I have shut down >> >> John A few more tests looks like the audioscrobbler plugin is responsible for the autoshutdown not working properly, I haven't tested the changes you've committed but I didn't see any there at a glance that would address it.
John John ------------------------------------------------------------------------- This SF.net email is sponsored by: Microsoft Defy all challenges. Microsoft(R) Visual Studio 2005. http://clk.atdmt.com/MRT/go/vse0120000070mrt/direct/01/ _______________________________________________ Freevo-users mailing list Freevo-users@lists.sourceforge.net https://lists.sourceforge.net/lists/listinfo/freevo-users
