Bug 2258 - Every day Alarm Clock problem
: Every day Alarm Clock problem
Status: RESOLVED FIXED
Product: Logitech Media Server
Classification: Unclassified
Component: Plugin
: 6.2.0
: Macintosh All
: P2 normal (vote)
: ---
Assigned To: KDF
:
Depends on:
Blocks:
  Show dependency treegraph
 
Reported: 2005-10-05 08:08 UTC by Julius Friede
Modified: 2008-09-15 14:36 UTC (History)
1 user (show)

See Also:
Category: ---


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description Julius Friede 2005-10-05 08:08:15 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) () () () () ()
Comment 1 KDF 2005-10-05 09:25:59 UTC
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?
Comment 2 KDF 2005-10-05 10:37:02 UTC
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) () () () () ()
Comment 3 Julius Friede 2005-10-05 15:05:45 UTC
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.
Comment 4 KDF 2005-10-05 15:09:08 UTC
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.
Comment 5 KDF 2005-10-05 15:20:45 UTC
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
Comment 6 Blackketter Dean 2005-10-05 15:28:58 UTC
KDF: you ok with me assigning this to you?
Comment 7 KDF 2005-10-05 15:39:59 UTC
I'll take it on until I'm stumped :)
Comment 8 Julius Friede 2005-10-06 06:54:58 UTC
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.
Comment 9 KDF 2005-10-06 09:34:48 UTC
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.
Comment 10 Julius Friede 2005-10-06 09:42:29 UTC
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.
Comment 11 KDF 2005-10-06 10:00:16 UTC
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.
Comment 12 Julius Friede 2005-10-06 18:30:26 UTC
How do I file an RFE? Do i just use the standard bug report?
Comment 13 KDF 2005-10-06 19:27:05 UTC
yes, but use the "severity" menu and set it to "enhancement"