Bug 10536 - Songs won't advance to next.
: Songs won't advance to next.
Status: CLOSED FIXED
Product: Logitech Media Server
Classification: Unclassified
Component: Audio
: 7.3.1
: PC Windows Vista
: -- normal (vote)
: 7.3.3
Assigned To: James Richardson
:
Depends on:
Blocks:
  Show dependency treegraph
 
Reported: 2009-01-04 14:09 UTC by Paul Jaruszewski
Modified: 2009-01-28 07:46 UTC (History)
2 users (show)

See Also:
Category: ---


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description Paul Jaruszewski 2009-01-04 14:09:40 UTC
This has been an issue for a week or so now since I setup my system. I initially thought I was doing something incorrectly, but now believe it to be a deficiency in your software. I am running Squeeze Center on a WHS setup as follows.

Version: 7.3.1 - 24372 @ Fri Dec 19 17:56:44 PST 2008
Hostname: WHS
Server IP Address: 192.168.1.157
Server HTTP Port Number: 9000
Operating system: Windows Server 2003 - EN - cp1252
Platform Architecture: 586
Perl Version: 5.8.8 - MSWin32-x86-multi-thread
MySQL Version: 5.0.22-community-nt
Total Players Recognized: 1


I have one player and it is connected via wireless Ethernet. When playing a playlist of MP3s from my WHS it periodically will fail to advance to the next song. The remote shows that it is still playing and it displays a time into the song and time remaining that progress, but then get thrown back by about 30 seconds and this repeats. It does not repeat the last 30 seconds of the song, but continutes to play silence. Interesting not is the time remaining seems to count down from -59.59 to -59:29 and then repeat starting again at -59:59 and running down in real time to -59:29. The time on the left does a similar thing counting up for 30 seconds and then cycling. 

I have not seen any behavior like this when playing music streamed via Pandora or any other provider, only my own playlists. Please help as this destroys the functionality of the player.

I tried to capture some logs. Hope they help. I also posted on this thread: http://forums.slimdevices.com/showthread.php?t=57456

Hopefully someone can make sense of this.



[09-01-04 13:38:03.8402] Slim::Player::StreamingController::_eventAction (269) 00:04:20:16:aa:c2: StatusHeartbeat in PLAYING-TRACKWAIT -> Slim::Player::StreamingController::_CheckSync
[09-01-04 13:38:04.0336] Slim::Player::StreamingController::_eventAction (269) 00:04:20:16:aa:c2: StatusHeartbeat in PLAYING-TRACKWAIT -> Slim::Player::StreamingController::_CheckSync



This is what happened when I advanced the track via the remote.



[09-01-04 13:44:18.8140] Slim::Player::StreamingController::_eventAction (269) 00:04:20:16:aa:c2: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-01-04 13:44:19.8141] Slim::Player::StreamingController::_eventAction (269) 00:04:20:16:aa:c2: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-01-04 13:44:20.8134] Slim::Player::StreamingController::_eventAction (269) 00:04:20:16:aa:c2: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-01-04 13:44:21.0154] Slim::Player::StreamingController::_eventAction (269) 00:04:20:16:aa:c2: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-01-04 13:44:21.0170] Slim::Player::StreamingController::_eventAction (269) 00:04:20:16:aa:c2: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-01-04 13:44:21.8089] Slim::Player::StreamingController::_eventAction (269) 00:04:20:16:aa:c2: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-01-04 13:44:22.8140] Slim::Player::StreamingController::_eventAction (269) 00:04:20:16:aa:c2: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[09-01-04 13:44:23.0058] Slim::Player::Protocols::File::sysread (211) Trying to read past the end of file:
[09-01-04 13:44:23.0065] Slim::Player::Source::_readNextChunk (476) Read to end of file or pipe
[09-01-04 13:44:23.0678] Slim::Player::Source::_readNextChunk (499) end of file or error on socket, song pos: 5223093, tell says: 5223093, totalbytes: 5202808
[09-01-04 13:44:23.0687] Slim::Player::Source::_readNextChunk (515) 00:04:20:16:aa:c2 mark end of stream
[09-01-04 13:44:23.0708] Slim::Player::StreamingController::_eventAction (269) 00:04:20:16:aa:c2: LocalEndOfStream in PLAYING-STREAMING -> Slim::Player::StreamingController::_Streamout
[09-01-04 13:44:23.0716] Slim::Player::StreamingController::_setStreamingSt ate (1792) new streaming state STREAMOUT
[09-01-04 13:44:23.0726] Slim::Player::StreamingController::_eventAction (301) 00:04:20:16:aa:c2: LocalEndOfStream - new state PLAYING-STREAMOUT
[09-01-04 13:44:23.8076] Slim::Player::StreamingController::_eventAction (269) 00:04:20:16:aa:c2: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[09-01-04 13:44:24.8058] Slim::Player::StreamingController::_eventAction (269) 00:04:20:16:aa:c2: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[09-01-04 13:44:25.4222] Slim::Player::StreamingController::playerEndOfStre am (1688) 00:04:20:16:aa:c2
[09-01-04 13:44:25.4235] Slim::Player::StreamingController::_eventAction (269) 00:04:20:16:aa:c2: EndOfStream in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_AutoStart
[09-01-04 13:44:25.4381] Slim::Player::StreamingController::_setStreamingSt ate (1792) new streaming state STREAMOUT
[09-01-04 13:44:25.8140] Slim::Player::StreamingController::_eventAction (269) 00:04:20:16:aa:c2: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[09-01-04 13:44:26.0324] Slim::Player::StreamingController::_eventAction (269) 00:04:20:16:aa:c2: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[09-01-04 13:44:26.0351] Slim::Player::StreamingController::_eventAction (269) 00:04:20:16:aa:c2: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync


Paul Jaruszewski
Comment 1 Lance Berc 2009-01-06 21:54:23 UTC
Me, too.

I've installed Version: 7.3.1 - 24372 on a Windows XP server and have similar problems with a pair of SB1s.  If the receivers are solo they work fine, but if I synchronize a pair they play only the first song of any play list.

Happy to gather whatever we need to fix this.  It is 100% reproducible.
Comment 2 James Richardson 2009-01-07 15:57:26 UTC
(In reply to comment #1)
> Me, too.
> 
> I've installed Version: 7.3.1 - 24372 on a Windows XP server and have similar
> problems with a pair of SB1s.  If the receivers are solo they work fine, but if
> I synchronize a pair they play only the first song of any play list.
> 
> Happy to gather whatever we need to fix this.  It is 100% reproducible.
> 

Lance, you are reporting is a different issue, please open a new bug Product ScqueezseNetwork / Component Sync
Comment 3 James Richardson 2009-01-07 15:59:13 UTC
Paul:

What format of music are you trying to stream to the controller?

What firmware version do you have on the controller?

Does this happen with all tracks? or just a few tracks?

If you disable playback on the SB Controller, and play to the SB Receiver instead, does it work properly?
Comment 4 Paul Jaruszewski 2009-01-08 06:55:55 UTC
James, my entire music collection consists of MP3's. On the Squeezebox Duet Controller under About, it says I am using version 7.3r3476. I haven't installed any of the plugins or customization other than placing a few of my own images for backgrounds as screen savers. Other than that, it is factory fresh. I bought and setup right before Christmas.

This occurs irregularly. The last time I let it play for an extended period of time was last weekend. I walked back into the room and no music. It was caught with this bug and not stopped or timed out, etc. After fooling with the controller it started up again and played for a few hours till I turned it off without stopping again. To the best of my knowledge it hasn't happened with the same song twice. The track it gets stuck on appears random.

"If you disable playback on the SB Controller, and play to the SB Receiver
instead, does it work properly?"

I am not sure what you mean by that question. The music plays from the receiver unit that is hooked up to my home stereo. I am using the controller to control the setup. I might be misunderstanding this question, please elaborate so I can answer this question.



 
Paul

Comment 5 James Richardson 2009-01-08 07:18:25 UTC
Paul: than you for clarifying, I was confused by the Product / Component you selected when setting up the bug.  I have corrected that now.

Does this error happen only with Play Lists or can you reproduce playing an Artist or Track as well?

If only play lists have the problem, were the play lists created with SqueezeCenter or another application?

Please the following logs to DEBUG:
Settings > Advanced > Logging;
player.source = Debug

Once you have done that, replicate the error.  Then, attach the log file to this bug, noting approximately what time the error happened.
Comment 6 Paul Jaruszewski 2009-01-08 07:41:29 UTC
James, this occurs when playing playlists generated by the SC software. I can't remember if the playlist was created with the web interface or with the controller. I will play with it this weekend and see if it occurs with album or artist settings. I will also try to setup the logs as you request and hopefully catch it in it's error state and forward the logs. 

I just set it up to play as I go to work. Will check it when I return tonight. Is playing a playlist I made with the web-based software.

Paul

Comment 7 Paul Jaruszewski 2009-01-24 18:41:08 UTC
Possible cause of problem. To be honest this bug is driving me nuts. Today I noticed twice that the song BEFORE a song with an apostrophe ( squote to old time programmers, single quote, ('))

Check this out as a possibility. It happened twice today and I have never noticed one way or the other before. If this is the problem, it should be an easy fix!

Paul

Comment 8 Paul Jaruszewski 2009-01-24 18:59:27 UTC
Please read my comment above first.. I think it might be only on AAC files with an apostrophe. Will do more testing. 

Paul

Comment 9 Paul Jaruszewski 2009-01-24 19:05:52 UTC
Sorry, not AAC, but M4A files. My mistake.

Paul