Bugzilla – Bug 2258
Every day Alarm Clock problem
Last modified: 2008-09-15 14:36:01 UTC
Thanks for taking care of Bug 2240. It now works:-). However, there seems to be a problem with the every day alarm setting. It doesn’t work, although it used to. I have attached log entries at the end. I have also found an intermittent problem with entering data in all alarms. Sometimes when I enter a start time, after clicking “change”, the message feedback confirms the change. In reality, however, the time defaults to 12:00 am. This is confirmed by either a) the alarm not activating as expected and checking the setting, or b) changing to a different page (i.e. “Display”) and then returning to Alarms shows the 12:00 am time. This doesn’t happen all the time, but a lot. For “every day alarm“, the following log entry: the activation time came, the current playlist in SlimServer was deleted, then nothing. The selected playlist was not loaded. SlimServer Version: 6.2b1 - 4549 - Mac OS X 10.4.2 (8C46) - EN - utf8 2005-10-05 08:02:07.8246 Executing command 00:04:20:05:3a:8e: stop () () () () () () () 2005-10-05 08:02:07.8280 RandomPlay: received command stop 2005-10-05 08:02:07.8285 RandomPlay: while in mode: track, from Master Bedroom 2005-10-05 08:02:07.8288 Returning array: stop () () () () () () () 2005-10-05 08:02:07.8291 Executing command 00:04:20:05:3a:8e: mixer (volume) (100) () () () () () 2005-10-05 08:02:07.8307 RandomPlay: received command mixer volume 100 2005-10-05 08:02:07.8310 RandomPlay: while in mode: track, from Master Bedroom 2005-10-05 08:02:07.8312 Returning array: mixer (volume) (100) () () () () () 2005-10-05 08:02:07.8319 Executing command 00:04:20:05:3a:8e: power (1) () () () () () () 2005-10-05 08:02:07.8322 RandomPlay: received command power 1 2005-10-05 08:02:07.8324 RandomPlay: while in mode: track, from Master Bedroom 2005-10-05 08:02:07.8326 Returning array: power (1) () () () () () () 2005-10-05 08:02:07.8420 Executing command 00:04:20:05:3a:8e: playlist (load) (itunesplaylist:Good%20Rockin'%20Tonight-Legacy%20of%20Sun%20Records) () () () () () 2005-10-05 08:02:07.8573 RandomPlay: received command playlist load_done 2005-10-05 08:02:07.8577 RandomPlay: while in mode: track, from Master Bedroom 2005-10-05 08:02:07.8582 RandomPlay: received command playlist load itunesplaylist:Good%20Rockin'%20Tonight-Legacy%20of%20Sun%20Records 2005-10-05 08:02:07.8585 RandomPlay: while in mode: track, from Master Bedroom 2005-10-05 08:02:07.8586 Returning array: playlist (load) (itunesplaylist:Good%20Rockin'%20Tonight-Legacy%20of%20Sun%20Records) () () () () ()
Does the resetting time happen for your daily alarms as well? What playlist were you trying to load? Did you have a random mix going at some point prior to this alarm?
I have tried this out, with a random mix going, timing an itunes playlist to load via the every day alarm. I cannot get the time setting to fail at all. The log will follow for the alarm event. You'll note that after the load of itunes playlist, there is an command "open" for the first song. This is missing from your log, so I'm wondering if this was a case of a bad track in the playlist, or just another example of bug 2245 (case mentioned there is a stop mid-track, but I sometimes experience a non-start). Either way, it seems that playlist "load" is missing as a case for stopping Random mode. If you can reproduce this reliably, maybe try adding d_playlist and d_source to the debugging. my d_plugins d_command log as follows: 2005-10-05 10:29:00.1538 RandomPlay: received command stop 2005-10-05 10:29:00.1546 RandomPlay: while in mode: track, from SoftSqueeze 2005-10-05 10:29:00.1552 Returning array: stop () () () () () () () 2005-10-05 10:29:00.1557 Executing command f0:5a:f4:24:93:98: mixer (volume) (20) () () () () () 2005-10-05 10:29:00.1568 RandomPlay: received command mixer volume 20 2005-10-05 10:29:00.1658 RandomPlay: while in mode: track, from SoftSqueeze 2005-10-05 10:29:00.1666 Returning array: mixer (volume) (20) () () () () () 2005-10-05 10:29:00.1674 Executing command f0:5a:f4:24:93:98: power (1) () () () () () () 2005-10-05 10:29:00.1680 RandomPlay: received command power 1 2005-10-05 10:29:00.1684 RandomPlay: while in mode: track, from SoftSqueeze 2005-10-05 10:29:00.1691 Returning array: power (1) () () () () () () 2005-10-05 10:29:00.1842 Executing command f0:5a:f4:24:93:98: playlist (load) (itunesplaylist:80%E2%80%99s%20Music) () () () () () 2005-10-05 10:29:00.2985 RandomPlay: received command open file:///D:/mp3/Misc/A-HA%20-%20(11)%20Take%20on%20me%20(Techno%20mix).mp3 2005-10-05 10:29:00.2992 RandomPlay: while in mode: track, from SoftSqueeze 2005-10-05 10:29:00.3110 RandomPlay: received command playlist load_done 2005-10-05 10:29:00.3116 RandomPlay: while in mode: track, from SoftSqueeze 2005-10-05 10:29:00.4394 RandomPlay: received command playlist load itunesplaylist:80%E2%80%99s%20Music 2005-10-05 10:29:00.4400 RandomPlay: while in mode: track, from SoftSqueeze 2005-10-05 10:29:00.4408 Returning array: playlist (load) (itunesplaylist:80%E2%80%99s%20Music) () () () () ()
The time reset problem happens with both daily and every day alarms. It doesn’t happen every time, but frequently. As to the every day alarm not playing a playlist, there is no specific playlist that this happens with. The same playlist on a daily alarm activates properly. I had been playing around with random song mix plugin (bug 2240), so it is possible that this was selected prior to an attempt. I will experiment further and add d_playlist and d_source to the debugging.
Thanks. I am unable to reproduce this problem, so logs are all I'll be able to go by, unless someone at SD can see it happening. Unrelated to the problem, I've realised that the alarm is using an older command for loading playlists. This should be updated, so I'll be merging in a patch tonight to update that. Slim chance this will affect what you are seeing, but worth noting in case something does change when using tomorrow's build.
I had another thought. If you see the time reset to 12am again, could you stop for a sec, grab a copy of your prefs file and attach it to the report, before you change the setting back to what you want. On osx, the prefs file should be ~/Library/SlimDevices/slimserver.pref
KDF: you ok with me assigning this to you?
I'll take it on until I'm stumped :)
I set the Everyday alarm and the loaded playlist was cleared but the playlist selected with the alarm was not loaded. I will download the new build and see if that helps. Here are the logs: SlimServer Version: 6.2b1 - 4549 - Mac OS X 10.4.2 (8C46) - EN - utf8 2005-10-06 06:30:05.6887 Executing command 00:04:20:05:3a:8e: stop () () () () () () () 2005-10-06 06:30:05.6904 Backtrace: frame 0: Slim::Player::Source::playmode (/Users/juliusf/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Command.pm line 287) frame 1: Slim::Control::Command::execute (/Users/juliusf/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Client.pm line 985) frame 2: Slim::Player::Client::execute (/Users/juliusf/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/AlarmClock.pm line 324) frame 3: Slim::Buttons::AlarmClock::checkAlarms (/Users/juliusf/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Utils/Timers.pm line 104) frame 4: Slim::Utils::Timers::checkTimers (./slimserver.pl line 638) frame 5: main::idle (./slimserver.pl line 569) frame 6: main::main (./slimserver.pl line 1214) 2005-10-06 06:30:05.6944 00:04:20:05:3a:8e: Switching to mode stop from play 2005-10-06 06:30:05.6954 00:04:20:05:3a:8e New play mode: stop 2005-10-06 06:30:05.6961 Stopping and clearing out old chunks for client 00:04:20:05:3a:8e 2005-10-06 06:30:05.6997 Resetting song queue 2005-10-06 06:30:05.7004 Song queue is now 1 2005-10-06 06:30:05.7011 Playlist: Jumping to song index: 1 2005-10-06 06:30:05.7023 00:04:20:05:3a:8e: Current playmode: stop 2005-10-06 06:30:05.7098 Returning array: stop () () () () () () () 2005-10-06 06:30:05.7107 Executing command 00:04:20:05:3a:8e: mixer (volume) (100) () () () () () 2005-10-06 06:30:05.7138 Returning array: mixer (volume) (100) () () () () () 2005-10-06 06:30:05.7152 Executing command 00:04:20:05:3a:8e: power (1) () () () () () () 2005-10-06 06:30:05.7162 Returning array: power (1) () () () () () () 2005-10-06 06:30:05.7312 Executing command 00:04:20:05:3a:8e: playlist (load) (itunesplaylist:Good%20Rockin'%20Tonight-Legacy%20of%20Sun%20Records) () () () () () 2005-10-06 06:30:05.7329 Backtrace: frame 0: Slim::Player::Source::playmode (/Users/juliusf/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Control/Command.pm line 595) frame 1: Slim::Control::Command::execute (/Users/juliusf/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Player/Client.pm line 985) frame 2: Slim::Player::Client::execute (/Users/juliusf/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Buttons/AlarmClock.pm line 350) frame 3: Slim::Buttons::AlarmClock::checkAlarms (/Users/juliusf/Library/PreferencePanes/SlimServer.prefPane/Contents/server/Slim/Utils/Timers.pm line 104) frame 4: Slim::Utils::Timers::checkTimers (./slimserver.pl line 638) frame 5: main::idle (./slimserver.pl line 569) frame 6: main::main (./slimserver.pl line 1214) 2005-10-06 06:30:05.7346 00:04:20:05:3a:8e: Switching to mode stop from stop 2005-10-06 06:30:05.7354 Already in playmode stop : ignoring mode change 2005-10-06 06:30:05.7372 Adding song index 0 to song queue 2005-10-06 06:30:05.7389 Clearing out song queue first 2005-10-06 06:30:05.7510 Song queue is now 0 2005-10-06 06:30:05.7525 Playlist: Jumping to song index: 0 2005-10-06 06:30:05.8647 Adding song index 0 to song queue 2005-10-06 06:30:05.8656 Clearing out song queue first 2005-10-06 06:30:05.8661 Song queue is now 0 2005-10-06 06:30:05.8711 Returning array: playlist (load) (itunesplaylist:Good%20Rockin'%20Tonight-Legacy%20of%20Sun%20Records) () () () () () 2005-10-06 06:38:06.0869 Requesting web page to keep SlimServer unswapped.
Thanks Julius. I'm not seeing anything that stands out in that log as a problem. I think I'd need more detail further on. After you try today's build, attach the log starting from "Executing command 00:04:20:05:3a:8e: playlist (playtracks)" and hopefully we'll see the song play command or why the track fails to play. could you also attach your prefs file. I think I'll possibly see anything wrong with that regardless of the time pref failing. You could also try d_prefs debugging while you change the time setting.
I installed the 10/6 build and the everyday alarm worked as it should. Good job. I haven’t experienced the alarm not retaining it’s set time and defaulting to 12am since my last post. If it happens again I’ll post it as a new bug. I did have some response problems (via the remote) with this new nightly, but in hindsight I think it was the server rescanning my library. I can’t be sure since the response was excellent by the time I was able to get to the computer (morning routine). It would be nice if the player(s) displayed a little icon when the library was being rescanned to let users know there is probably not a problem occurring. Just a thought. It could also be a preference.
good to hear. I'll close this off for now. Please do re-open or create a new report if the issues you spoke of return somewhere along the way. An icon on the screen would be cool. Perhaps file an RFE for that.
How do I file an RFE? Do i just use the standard bug report?
yes, but use the "severity" menu and set it to "enhancement"