Bug 11582 – Snooze doesn't work any more - sleeping in x minutes instead

Bug 11582 - Snooze doesn't work any more - sleeping in x minutes instead

Summary:

Snooze doesn't work any more - sleeping in x minutes instead

Status:	CLOSED FIXED

Product:	Logitech Media Server
Classification:	Unclassified
Component:	Alarm
Version:	7.4.0
Platform:	PC Other

Importance:	P2 normal with 1 vote (vote)
Target Milestone:	7.4.0
Assigned To:	Alan Young

URL:
Keywords:

Depends on:
Blocks:
	Show dependency tree / graph

Reported:	2009-04-01 01:19 UTC by Michael Herger
Modified:	2009-10-05 14:37 UTC (History)
CC List:	5 users (show)

See Also:
Category:	---

Attachments
Ignore unwanted notifications (1.18 KB, patch) 2009-04-08 12:40 UTC, Alan Young	Details \| Diff
View All Add an attachment (proposed patch, testcase, etc.)

Note You need to log in before you can comment on or make changes to this bug.

Description Michael Herger 2009-04-01 01:19:47 UTC

I noticed that recently the alarm wouldn't display the datetime screensaver any more, but whatever Now Playing screensaver is set. Furthermore, hitting the snooze button wouldn't snooze, but trigger the "Sleeping in x minutes" feature.

Don't know when this started. Revision 25547 is still fine, 25665 isn't.

Comment 1 James Richardson 2009-04-01 14:12:45 UTC

Works for me on test.sn

Fails with 7.4 r25765

Comment 2 James Richardson 2009-04-01 14:15:57 UTC

[09-04-01 14:13:53.6258] Slim::Utils::Alarm::save (996) Saving alarm.
[09-04-01 14:13:53.6262] Slim::Utils::Alarm::save (1016) Alarm saved with id cd1beeae Rescheduling alarms...
[09-04-01 14:13:53.6264] Slim::Utils::Alarm::scheduleNext (1333) Asked to schedule next alarm for BOOM:04:a4
[09-04-01 14:13:53.6266] Slim::Utils::Alarm::scheduleNext (1339) Previous scheduled alarm wasn't triggered.  Clearing nextAlarm and killing timer
[09-04-01 14:13:53.6270] Slim::Utils::Alarm::_startStopTimeCheck (1800) 0 scheduled alarm(s)
[09-04-01 14:13:53.6272] Slim::Utils::Alarm::_startStopTimeCheck (1809) Stopping time checker task
[09-04-01 14:13:53.6275] Slim::Utils::Alarm::findNextTime (457) Potential next time found: 14:15:0 1/4/2009
[09-04-01 14:13:53.6277] Slim::Utils::Alarm::findNextTime (462) Last alarm due: 14:10:0 1/4/2009
[09-04-01 14:13:53.6279] Slim::Utils::Alarm::scheduleNext (1366) Next alarm is at 14:15:0 1/4/2009
[09-04-01 14:13:53.6281] Slim::Utils::Alarm::scheduleNext (1378) Scheduling alarm
[09-04-01 14:13:53.6284] Slim::Utils::Alarm::_startStopTimeCheck (1800) 1 scheduled alarm(s)
[09-04-01 14:13:53.6285] Slim::Utils::Alarm::_startStopTimeCheck (1804) Starting time checker task
[09-04-01 14:13:53.6288] Slim::Utils::Alarm::setRTCAlarm (1412) Asked to set rtc alarm for BOOM:04:a4
[09-04-01 14:13:53.6292] Slim::Utils::Alarm::setRTCAlarm (1441) Setting RTC alarm to 51300, volume 50
[09-04-01 14:13:54.0068] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:13:54.0076] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:13:54.6723] Slim::Utils::Alarm::save (996) Saving alarm.
[09-04-01 14:13:54.6727] Slim::Utils::Alarm::save (1016) Alarm saved with id cd1beeae Rescheduling alarms...
[09-04-01 14:13:54.6730] Slim::Utils::Alarm::scheduleNext (1333) Asked to schedule next alarm for BOOM:04:a4
[09-04-01 14:13:54.6732] Slim::Utils::Alarm::scheduleNext (1339) Previous scheduled alarm wasn't triggered.  Clearing nextAlarm and killing timer
[09-04-01 14:13:54.6735] Slim::Utils::Alarm::_startStopTimeCheck (1800) 0 scheduled alarm(s)
[09-04-01 14:13:54.6737] Slim::Utils::Alarm::_startStopTimeCheck (1809) Stopping time checker task
[09-04-01 14:13:54.6741] Slim::Utils::Alarm::findNextTime (457) Potential next time found: 14:15:0 1/4/2009
[09-04-01 14:13:54.6743] Slim::Utils::Alarm::findNextTime (462) Last alarm due: 14:10:0 1/4/2009
[09-04-01 14:13:54.6745] Slim::Utils::Alarm::scheduleNext (1366) Next alarm is at 14:15:0 1/4/2009
[09-04-01 14:13:54.6748] Slim::Utils::Alarm::scheduleNext (1378) Scheduling alarm
[09-04-01 14:13:54.6750] Slim::Utils::Alarm::_startStopTimeCheck (1800) 1 scheduled alarm(s)
[09-04-01 14:13:54.6753] Slim::Utils::Alarm::_startStopTimeCheck (1804) Starting time checker task
[09-04-01 14:13:54.6755] Slim::Utils::Alarm::setRTCAlarm (1412) Asked to set rtc alarm for BOOM:04:a4
[09-04-01 14:13:54.6760] Slim::Utils::Alarm::setRTCAlarm (1441) Setting RTC alarm to 51300, volume 50
[09-04-01 14:13:59.0090] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:13:59.0102] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:04.0076] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:04.0087] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:09.0058] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:09.0067] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:14.0054] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:14.0064] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:19.0054] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:19.0062] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:24.0054] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:24.0062] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:29.0053] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:29.0061] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:34.0295] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:34.0310] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:39.0068] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:39.0076] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:44.0047] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:44.0063] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:49.0360] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:49.0369] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:54.0088] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:54.0100] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:59.0052] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:14:59.0064] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:00.0010] Slim::Utils::Alarm::sound (510) Alarm triggered for BOOM:04:a4
[09-04-01 14:15:00.0017] Slim::Utils::Alarm::sound (576) Sounding alarm
[09-04-01 14:15:00.0069] Slim::Player::StreamingController::playerActive (1525) 00:04:20:1e:04:a4 player already active: 00:04:20:1e:04:a4
[09-04-01 14:15:00.0083] Slim::Utils::Alarm::pushAlarmScreensaver (1761) Attempting to push into alarm screensaver: SCREENSAVER.datetime. Current mode: INPUT.List
[09-04-01 14:15:00.0086] Slim::Utils::Alarm::pushAlarmScreensaver (1767) Pushing alarm screensaver
[09-04-01 14:15:00.0097] Slim::Utils::Alarm::sound (615) Current vol: 50 Alarm vol: 50
[09-04-01 14:15:00.0101] Slim::Utils::Alarm::sound (624) Alarm playlist url: randomplay://track
[09-04-01 14:15:00.0163] Slim::Player::StreamingController::stop (1624) 00:04:20:1e:04:a4
[09-04-01 14:15:00.0166] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: Stop in PAUSED-STREAMOUT -> Slim::Player::StreamingController::_Stop
[09-04-01 14:15:00.0181] Slim::Player::StreamingController::_Stop (548) Song queue is now 0
[09-04-01 14:15:00.0187] Slim::Player::SongStreamController::DESTROY (45) DESTROY(Slim::Player::SongStreamController=HASH(0x3627624)) live=0
[09-04-01 14:15:00.0190] Slim::Player::StreamingController::_setPlayingState (1881) new playing state STOPPED
[09-04-01 14:15:00.0192] Slim::Player::StreamingController::_setStreamingState (1894) new streaming state IDLE
[09-04-01 14:15:00.0196] Slim::Player::StreamingController::_eventAction (301) 00:04:20:1e:04:a4: Stop - new state STOPPED-IDLE
[09-04-01 14:15:00.0201] Slim::Player::Source::playmode (172) 00:04:20:1e:04:a4: Current playmode: stop
[09-04-01 14:15:00.0223] Slim::Player::StreamingController::play (1627) 00:04:20:1e:04:a4
[09-04-01 14:15:00.0225] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: Play in STOPPED-IDLE -> Slim::Player::StreamingController::_StopGetNext
[09-04-01 14:15:00.0228] Slim::Player::StreamingController::_eventAction (282) params: index => 0 seekdata => undef
[09-04-01 14:15:00.0264] Slim::Player::StreamingController::_Stop (548) Song queue is now 0
[09-04-01 14:15:00.0267] Slim::Player::StreamingController::_setPlayingState (1881) new playing state STOPPED
[09-04-01 14:15:00.0269] Slim::Player::StreamingController::_setStreamingState (1894) new streaming state IDLE
[09-04-01 14:15:00.0275] Slim::Player::Song::new (64) index 0 -> randomplay://track
[09-04-01 14:15:00.0278] Slim::Player::Song::new (101) live=4
[09-04-01 14:15:00.0280] Slim::Player::StreamingController::_setStreamingState (1894) new streaming state TRACKWAIT
[09-04-01 14:15:00.0283] Slim::Player::Song::DESTROY (111) DESTROY(Slim::Player::Song=HASH(0x3632e70)) live=3: index=0, url=file:///Users/James/Music/BUG7857/mmw-deadzy.ogg
[09-04-01 14:15:00.0290] Slim::Player::Song::getNextSong (178) randomplay://track
[09-04-01 14:15:00.0293] Slim::Player::StreamingController::_nextTrackReady (667) 00:04:20:1e:04:a4: nextTrack will be index 0
[09-04-01 14:15:00.0295] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: NextTrackReady in STOPPED-TRACKWAIT -> Slim::Player::StreamingController::_Stream
[09-04-01 14:15:00.0297] Slim::Player::StreamingController::_Stream (941) 00:04:20:1e:04:a4: preparing to stream song index 0
[09-04-01 14:15:00.0300] Slim::Player::StreamingController::_Stream (957) Song queue is now 0
[09-04-01 14:15:00.0302] Slim::Player::StreamingController::_Stream (964) Protocol Handler for randomplay://track overriding playback
[09-04-01 14:15:00.0379] Slim::Player::StreamingController::stop (1624) 00:04:20:1e:04:a4
[09-04-01 14:15:00.0381] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: Stop in STOPPED-TRACKWAIT -> Slim::Player::StreamingController::_Stop
[09-04-01 14:15:00.0398] Slim::Player::StreamingController::_Stop (548) Song queue is now 0
[09-04-01 14:15:00.0400] Slim::Player::StreamingController::_setPlayingState (1881) new playing state STOPPED
[09-04-01 14:15:00.0402] Slim::Player::StreamingController::_setStreamingState (1894) new streaming state IDLE
[09-04-01 14:15:00.0405] Slim::Player::StreamingController::_eventAction (301) 00:04:20:1e:04:a4: Stop - new state STOPPED-IDLE
[09-04-01 14:15:00.0410] Slim::Player::Source::playmode (172) 00:04:20:1e:04:a4: Current playmode: stop
[09-04-01 14:15:00.0440] Slim::Player::StreamingController::play (1627) 00:04:20:1e:04:a4
[09-04-01 14:15:00.0442] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: Play in STOPPED-IDLE -> Slim::Player::StreamingController::_StopGetNext
[09-04-01 14:15:00.0445] Slim::Player::StreamingController::_eventAction (282) params: index => 0 seekdata => undef
[09-04-01 14:15:00.0456] Slim::Player::StreamingController::_Stop (548) Song queue is now 0
[09-04-01 14:15:00.0458] Slim::Player::StreamingController::_setPlayingState (1881) new playing state STOPPED
[09-04-01 14:15:00.0460] Slim::Player::StreamingController::_setStreamingState (1894) new streaming state IDLE
[09-04-01 14:15:00.0488] Slim::Player::Song::new (64) index 0 -> file:///Users/James/Music/BUG7857/mmw-deadzy.ogg
[09-04-01 14:15:00.0491] Slim::Player::Song::new (101) live=4
[09-04-01 14:15:00.0493] Slim::Player::StreamingController::_setStreamingState (1894) new streaming state TRACKWAIT
[09-04-01 14:15:00.0496] Slim::Player::Song::getNextSong (178) file:///Users/James/Music/BUG7857/mmw-deadzy.ogg
[09-04-01 14:15:00.0499] Slim::Player::StreamingController::_nextTrackReady (667) 00:04:20:1e:04:a4: nextTrack will be index 0
[09-04-01 14:15:00.0501] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: NextTrackReady in STOPPED-TRACKWAIT -> Slim::Player::StreamingController::_Stream
[09-04-01 14:15:00.0503] Slim::Player::StreamingController::_Stream (941) 00:04:20:1e:04:a4: preparing to stream song index 0
[09-04-01 14:15:00.0505] Slim::Player::StreamingController::_Stream (957) Song queue is now 0
[09-04-01 14:15:00.0508] Slim::Player::Song::open (302) file:///Users/James/Music/BUG7857/mmw-deadzy.ogg
[09-04-01 14:15:00.0519] Slim::Player::TranscodingHelper::getConvertCommand2 (324) rateLimit = 0, type = ogg, boom = 00:04:20:1e:04:a4
[09-04-01 14:15:00.0521] Slim::Player::TranscodingHelper::enabledFormat (194) Checking to see if ogg-ogg-*-* is enabled
[09-04-01 14:15:00.0524] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: ogg-wma-boom-00:04:20:1e:04:a4
[09-04-01 14:15:00.0526] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: ogg-wma-*-00:04:20:1e:04:a4
[09-04-01 14:15:00.0528] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: ogg-wma-boom-*
[09-04-01 14:15:00.0530] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: ogg-wma-*-*
[09-04-01 14:15:00.0532] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: ogg-ogg-boom-00:04:20:1e:04:a4
[09-04-01 14:15:00.0534] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: ogg-ogg-*-00:04:20:1e:04:a4
[09-04-01 14:15:00.0536] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: ogg-ogg-boom-*
[09-04-01 14:15:00.0538] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: ogg-ogg-*-*
[09-04-01 14:15:00.0540] Slim::Player::TranscodingHelper::enabledFormat (194) Checking to see if ogg-ogg-*-* is enabled
[09-04-01 14:15:00.0542] Slim::Player::TranscodingHelper::checkBin (235)    enabled
[09-04-01 14:15:00.0544] Slim::Player::TranscodingHelper::checkBin (237)   Found command: -
[09-04-01 14:15:00.0546] Slim::Player::TranscodingHelper::getConvertCommand2 (458) Matched: ogg->ogg via: -
[09-04-01 14:15:00.0548] Slim::Player::Song::open (323) seek=false time=0 canSeek=1
[09-04-01 14:15:00.0552] Slim::Player::TranscodingHelper::getConvertCommand2 (324) rateLimit = 0, type = ogg, boom = 00:04:20:1e:04:a4
[09-04-01 14:15:00.0554] Slim::Player::TranscodingHelper::enabledFormat (194) Checking to see if ogg-ogg-*-* is enabled
[09-04-01 14:15:00.0556] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: ogg-wma-boom-00:04:20:1e:04:a4
[09-04-01 14:15:00.0558] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: ogg-wma-*-00:04:20:1e:04:a4
[09-04-01 14:15:00.0560] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: ogg-wma-boom-*
[09-04-01 14:15:00.0562] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: ogg-wma-*-*
[09-04-01 14:15:00.0564] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: ogg-ogg-boom-00:04:20:1e:04:a4
[09-04-01 14:15:00.0566] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: ogg-ogg-*-00:04:20:1e:04:a4
[09-04-01 14:15:00.0568] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: ogg-ogg-boom-*
[09-04-01 14:15:00.0570] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: ogg-ogg-*-*
[09-04-01 14:15:00.0572] Slim::Player::TranscodingHelper::enabledFormat (194) Checking to see if ogg-ogg-*-* is enabled
[09-04-01 14:15:00.0574] Slim::Player::TranscodingHelper::checkBin (235)    enabled
[09-04-01 14:15:00.0576] Slim::Player::TranscodingHelper::checkBin (237)   Found command: -
[09-04-01 14:15:00.0578] Slim::Player::TranscodingHelper::getConvertCommand2 (458) Matched: ogg->ogg via: -
[09-04-01 14:15:00.0580] Slim::Player::Song::open (340) Transcoder: streamMode=I, streamformat=ogg
[09-04-01 14:15:00.0583] Slim::Player::Song::open (364) Opening stream (no direct streaming) using Slim::Player::Protocols::File [file:///Users/James/Music/BUG7857/mmw-deadzy.ogg]
[09-04-01 14:15:00.0589] Slim::Player::Protocols::File::open (79) duration: [246] size: [8205626] endian [] offset: [0] for file:///Users/James/Music/BUG7857/mmw-deadzy.ogg
[09-04-01 14:15:00.0591] Slim::Player::Protocols::File::open (96) Opening file /Users/James/Music/BUG7857/mmw-deadzy.ogg
[09-04-01 14:15:00.0596] Slim::Player::Song::open (385) URL is a song (audio): file:///Users/James/Music/BUG7857/mmw-deadzy.ogg, type=ogg
[09-04-01 14:15:00.2137] Slim::Player::SongStreamController::new (32) live=1
[09-04-01 14:15:00.2148] Slim::Player::StreamingController::_Stream (1010) 00:04:20:1e:04:a4: stream
[09-04-01 14:15:00.2156] Slim::Player::Boom::play (335) Setting LineIn to 0 for [file:///Users/James/Music/BUG7857/mmw-deadzy.ogg]
[09-04-01 14:15:00.2160] Slim::Player::Boom::setLineIn (424) Switching to line in 0
[09-04-01 14:15:00.2172] Slim::Player::StreamingController::_Stream (1040) Song queue is now 0
[09-04-01 14:15:00.2177] Slim::Player::StreamingController::_setPlayingState (1881) new playing state BUFFERING
[09-04-01 14:15:00.2180] Slim::Player::StreamingController::_setStreamingState (1894) new streaming state STREAMING
[09-04-01 14:15:00.2183] Slim::Player::StreamingController::_eventAction (301) 00:04:20:1e:04:a4: NextTrackReady - new state BUFFERING-STREAMING
[09-04-01 14:15:00.2187] Slim::Player::StreamingController::_eventAction (301) 00:04:20:1e:04:a4: Play - new state BUFFERING-STREAMING
[09-04-01 14:15:00.2269] Slim::Player::StreamingController::_eventAction (301) 00:04:20:1e:04:a4: NextTrackReady - new state BUFFERING-STREAMING
[09-04-01 14:15:00.2272] Slim::Player::Song::DESTROY (111) DESTROY(Slim::Player::Song=HASH(0x35f1d14)) live=3: index=0, url=randomplay://track
[09-04-01 14:15:00.2274] Slim::Player::StreamingController::_eventAction (301) 00:04:20:1e:04:a4: Play - new state BUFFERING-STREAMING
[09-04-01 14:15:00.2290] Slim::Utils::Alarm::_setAlarmSubscription (1174) Adding alarm subscription
[09-04-01 14:15:00.2294] Slim::Utils::Alarm::sound (687) Scheduling time out in 3600 seconds
[09-04-01 14:15:00.2298] Slim::Utils::Alarm::_startStopTimeCheck (1800) 0 scheduled alarm(s)
[09-04-01 14:15:00.2300] Slim::Utils::Alarm::_startStopTimeCheck (1809) Stopping time checker task
[09-04-01 14:15:00.2303] Slim::Utils::Alarm::scheduleNext (1333) Asked to schedule next alarm for BOOM:04:a4
[09-04-01 14:15:00.2305] Slim::Utils::Alarm::findNextTime (457) Potential next time found: 14:15:0 1/4/2009
[09-04-01 14:15:00.2307] Slim::Utils::Alarm::findNextTime (462) Last alarm due: 14:15:0 1/4/2009
[09-04-01 14:15:00.2310] Slim::Utils::Alarm::findNextTime (467) Skipping..
[09-04-01 14:15:00.2312] Slim::Utils::Alarm::findNextTime (457) Potential next time found: 14:15:0 2/4/2009
[09-04-01 14:15:00.2314] Slim::Utils::Alarm::findNextTime (462) Last alarm due: 14:15:0 1/4/2009
[09-04-01 14:15:00.2316] Slim::Utils::Alarm::scheduleNext (1366) Next alarm is at 14:15:0 2/4/2009
[09-04-01 14:15:00.2318] Slim::Utils::Alarm::scheduleNext (1378) Scheduling alarm
[09-04-01 14:15:00.2320] Slim::Utils::Alarm::_startStopTimeCheck (1800) 1 scheduled alarm(s)
[09-04-01 14:15:00.2322] Slim::Utils::Alarm::_startStopTimeCheck (1804) Starting time checker task
[09-04-01 14:15:00.2324] Slim::Utils::Alarm::setRTCAlarm (1412) Asked to set rtc alarm for BOOM:04:a4
[09-04-01 14:15:00.2328] Slim::Utils::Alarm::setRTCAlarm (1441) Setting RTC alarm to 51300, volume 50
[09-04-01 14:15:00.2362] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:00.2368] Slim::Utils::Alarm::_alarmEnd (1880) _alarmEnd called with request: stop
[09-04-01 14:15:00.2370] Slim::Utils::Alarm::_alarmEnd (1891) Ignoring self-created request
[09-04-01 14:15:00.2383] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:00.2387] Slim::Utils::Alarm::_alarmEnd (1880) _alarmEnd called with request: power
[09-04-01 14:15:00.2389] Slim::Utils::Alarm::_alarmEnd (1891) Ignoring self-created request
[09-04-01 14:15:00.2440] Slim::Utils::Alarm::_alarmEnd (1880) _alarmEnd called with request: playlist stop
[09-04-01 14:15:00.2442] Slim::Utils::Alarm::_alarmEnd (1908) Stopping alarm
[09-04-01 14:15:00.2449] Slim::Utils::Alarm::popAlarmScreensaver (1786) Attempting to pop alarm screensaver.  Current mode: SCREENSAVER.datetime
[09-04-01 14:15:00.2451] Slim::Utils::Alarm::popAlarmScreensaver (1788) Popping alarm screensaver
[09-04-01 14:15:00.5660] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:00.5869] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:01.2956] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:02.2942] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:02.5577] Slim::Player::StreamingController::playerTrackStarted (1700) 00:04:20:1e:04:a4
[09-04-01 14:15:02.5582] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: Started in BUFFERING-STREAMING -> Slim::Player::StreamingController::_Playing
[09-04-01 14:15:02.5586] Slim::Player::StreamingController::_setPlayingState (1881) new playing state PLAYING
[09-04-01 14:15:02.5590] Slim::Player::StreamingController::_Playing (359) Song 0 has now started playing
[09-04-01 14:15:02.5604] Slim::Player::StreamingController::_Playing (388) Song queue is now 0
[09-04-01 14:15:02.5607] Slim::Player::StreamingController::_eventAction (301) 00:04:20:1e:04:a4: Started - new state PLAYING-STREAMING
[09-04-01 14:15:03.2947] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:04.0175] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:04.0183] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:04.3015] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:05.2935] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:06.2930] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:07.2927] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:08.2924] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:09.0053] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:09.0092] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:09.2916] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:10.2914] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:11.2910] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:12.2913] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:13.2909] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:14.0059] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:14.0067] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:14.2895] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:15.2890] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:16.2889] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:17.2884] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:18.2888] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:19.0058] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:19.0065] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:19.2873] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:20.2893] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:21.2879] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-04-01 14:15:21.9360] Slim::Player::StreamingController::pause (1643) 00:04:20:1e:04:a4
[09-04-01 14:15:21.9364] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: Pause in PLAYING-STREAMING -> Slim::Player::StreamingController::_Pause
[09-04-01 14:15:21.9367] Slim::Player::StreamingController::_setPlayingState (1881) new playing state PAUSED
[09-04-01 14:15:21.9376] Slim::Player::StreamingController::_eventAction (301) 00:04:20:1e:04:a4: Pause - new state PAUSED-STREAMING
[09-04-01 14:15:21.9384] Slim::Player::Source::playmode (172) 00:04:20:1e:04:a4: Current playmode: pause
[09-04-01 14:15:21.9468] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMING -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:21.9474] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMING -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:22.0481] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMING -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:22.1595] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMING -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:22.1736] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMING -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:22.1879] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMING -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:22.2017] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMING -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:22.2464] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMING -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:22.2477] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMING -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:22.2864] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMING -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:22.3056] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMING -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:22.3061] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMING -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:22.3163] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMING -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:24.0053] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMING -> Slim::Player::StreamingController::_NoOp
[09-04-01 14:15:24.0062] Slim::Player::StreamingController::_eventAction (269) 00:04:20:1e:04:a4: StatusHeartbeat in PAUSED-STREAMING -> Slim::Player::StreamingController::_NoOp

Comment 3 Michael Herger 2009-04-02 05:58:07 UTC

James - This bug is about SqueezeCenter, not SqueezeNetwork. Don't know what you wanted to show with the log, but does it work for you with SC 7.4? I've tested this on 3 different installations, and all behave the same.

Comment 4 James Richardson 2009-04-02 07:23:04 UTC

I was using SN as a comparison, to show that the problem WAS specific to SqueezeCenter.

All versions of 7.4 I have tried fail

Max:  Your thoughts?

Comment 5 Max Spicer 2009-04-02 07:31:10 UTC

No thoughts, I'm afraid, other than to confirm that it doesn't work for me either.  I haven't made any changes to the alarm code in a while.

Comment 6 Michael Herger 2009-04-02 09:17:38 UTC

Andy - you've done some changes in the alarm code which are SN related. Could you imagine this broke the screensaver/snooze mode on SC?

Comment 7 KDF 2009-04-04 17:55:44 UTC

working for me at 7.4v25809.

Comment 8 Michael Herger 2009-04-06 00:06:32 UTC

Hehe... must be somehow related to what screensaver you're using... or just my MIS saver: I was still able to reproduce this with the latest 7.4 trunk. Then I disabled MIS, restarted -> everything working fine again. Re-enabled MIS -> now it's still working!

Might need to file a bug against my own plugin instead... Updating my production system now to see how it acts.

Comment 9 Max Spicer 2009-04-06 11:07:48 UTC

There's two issues here.  I haven't observed the snooze not work.  However, I have noticed on some occasions that the time is not displayed during an active alarm with no user interaction.

Comment 10 Michael Herger 2009-04-08 01:02:47 UTC

> There's two issues here.

I'm not sure: I either see even more than two, or one big underlying issue. IMHO SC loses notion of running an alarm. Today I realised it did not only not show the screensaver, not handle the snooze correctly, but it didn't stop after the hour neither. It kept on playing.

How is SC made aware of the alarm clock? How does it know it's in alarm mode, needs to act differently? This mechanism (however it used to work) is broken.

Comment 11 Mike 2009-04-08 04:36:44 UTC

I am running 7.4b25816 and also have this issue.  I noticed it a few builds ago, but didn't have time to investigate (moving house!).

As with Michael, it seems that the alarm starts, then instantly changes to "playlist mode", where it forgets that it's in "alarm mode".

Comment 12 Michael Herger 2009-04-08 05:08:47 UTC

Alan - as you correctly noted in a comment in StreamingController.pm (around line 404) your change 25665 did break some of the alarm features. Any way we can have them back?

Comment 13 Michael Herger 2009-04-08 05:16:59 UTC

Kind of a dupe of bug 7781 (https://bugs-archive.lyrion.org/show_bug.cgi?id=7781#c30)

Comment 14 Alan Young 2009-04-08 08:36:33 UTC

Ok, I'll look at it. But not immediately.

Comment 15 Alan Young 2009-04-08 09:15:32 UTC

(In reply to comment #12)
> Alan - as you correctly noted in a comment in StreamingController.pm (around
> line 404) your change 25665 did break some of the alarm features. Any way we can
> have them back?

Well it was not intended to. The point was that the addition of 'player playlist stop' notification (was 'player stop') should not interfere. I guess that it has, somehow.

Comment 16 KDF 2009-04-08 11:12:56 UTC

I think if the alarm sees a playlist stop, that's a trigger for the alarm mode to end (being an indication of the user stoppping the alarm playback)

Comment 17 Alan Young 2009-04-08 12:40:02 UTC

Created attachment 5077 [details]
Ignore unwanted notifications

Comment 18 Alan Young 2009-04-08 12:40:38 UTC

Yes, the alarm is seeing a 'playlist stop', but why. When in alarm mode (not sleep), it subscribes to ['pause', 'stop', 'power']. The debug trace says that it is "Adding alarm subscription", not a sleep subscription.

The problem is that Slim::Control::Request::notify() checks the current request string against an RE of command verbs to see if this request should fire this subscription. It checks against the _requeststr member of the request, which is a concatenation of the request elements: 'playlist,stop' in this case. This means that the RE 'stop' element matches, even though this is not a 'stop' command.

I think that maybe it should match against _request->[0]. I have no idea what else this might break.

A simple option would be to modify the alarm code to ignore unexpected (not subscribed to) notifications. Proposed patch attached.

Comment 19 Michael Herger 2009-04-08 23:18:52 UTC

Alan -  that patch is working fine for me.

Comment 20 Max Spicer 2009-04-09 01:47:59 UTC

Slightly confused - my latest 7.4 is working fine without the patch.  Maybe my squeezecenter isn't restarting for some reason?

From my reading of Alan's comment, it sounds like the notification mechanism could do with fixing too.  Is there a bug open for this?

I say commit Alan's patch and I'll try and do a thorough test of it to see if the alarm still does what it should do.

Comment 21 Michael Herger 2009-04-09 02:12:45 UTC

> Slightly confused - my latest 7.4 is working fine without the patch.

Mine is not. Did you patch it?

> I say commit Alan's patch

done - change 25882

Comment 22 Alan Young 2009-04-09 08:59:41 UTC

BTW, I only see the problem if the player was playing before the alarm fires.

Comment 23 Max Spicer 2009-04-09 09:08:27 UTC

That might explain why I wasn't seeing the problem.  I hadn't applied Alan's patch.

Comment 24 James Richardson 2009-10-05 14:37:30 UTC

This bug has been marked as fixed in the 7.4.0 release version of SqueezeBox Server!
    * SqueezeCenter: 28672
    * Squeezebox 2 and 3: 130
    * Transporter: 80
    * Receiver: 65
    * Boom: 50
    * Controller: 7790
    * Radio: 7790  

Please see the Release Notes for all the details: http://wiki.slimdevices.com/index.php/Release_Notes

If you haven't already, please download and install the new version from http://www.logitechsqueezebox.com/support/download-squeezebox-server.html

If you are still experiencing this problem, feel free to reopen the bug with your new comments and we'll have another look.