PDA

View Full Version : Squeezecenter/box not displaying correct song



Nigel0
2008-12-18, 10:32
Not sure if right topic for thread, but I have recently moved my squeezecenter to a new XP box and updated to 7.3. I have a wired (very old original) squeezebox connected (as well as a wireless boom).

Now it is all working fine *except* its started getting out of sync with the song that it says it is playing on both the squeezebox *and* in the web UI. It seems to stay one song out - i.e. shows the title and information of the previous song that played (and the progress/time bar is from that song)


It seems to reliably go into this mode a few songs into a random mix play list and it then continues to be one song behind from then on.
Pausing and playing doesn't fix, however if I press the next song (ffwd) button it will change the display/UI to the correct song *but* will restart from the beginning.

If that description made any sense, does anyone have any ideas what might be wrong and how to fix? Or maybe what debug logging it would be good for me to turn on (I think I have warnings all turned on and haven't seen anything unusual)

[BTW I haven't noticed same problem with the boom but I have had that connected to squeezenetwork mostly)

Nigel0
2008-12-19, 13:11
I suspect I may be talking to myself <g> but I turned on some debug logging and caught it in the act of going wrong.

It was playing "Peices of What" but displaying the previous track title "4th Dimensional Transition" for the entire track.

[08-12-19 19:33:57.1741] Slim::Player::Song::open (340) Transcoder: streamMode=I, streamformat=mp3
[08-12-19 19:33:57.1749] Slim::Player::Song::open (364) Opening stream (no direct streaming) using Slim::Player::Protocols::File [file:///D:/AllMusic/Music/Oracular%20Spectacular/07%20-%20Pieces%20of%20What.mp3]
[08-12-19 19:33:57.1779] Slim::Player::Protocols::File::open (79) duration: [163.814] size: [5113279] endian [] offset: [48086] for file:///D:/AllMusic/Music/Oracular%20Spectacular/07%20-%20Pieces%20of%20What.mp3
[08-12-19 19:33:57.1787] Slim::Player::Protocols::File::open (96) Opening file D:\AllMusic\Music\Oracular Spectacular\07 - Pieces of What.mp3
[08-12-19 19:33:57.1807] Slim::Player::Protocols::File::open (169) Seeking in 48086 into D:\AllMusic\Music\Oracular Spectacular\07 - Pieces of What.mp3
[08-12-19 19:33:57.1821] Slim::Player::Song::open (385) URL is a song (audio): file:///D:/AllMusic/Music/Oracular%20Spectacular/07%20-%20Pieces%20of%20What.mp3, type=mp3
[08-12-19 19:33:57.1991] Slim::Schema::forceCommit (1500) forceCommit ignored, database is in AutoCommit mode
[08-12-19 19:33:57.2001] Slim::Player::SongStreamController::new (32) live=2
[08-12-19 19:33:57.2016] Slim::Player::SongStreamController::DESTROY (45) DESTROY(Slim::Player::SongStreamController=HASH(0x 7d67914)) live=1
[08-12-19 19:33:57.2024] Slim::Player::StreamingController::_Stream (956) 00:04:20:05:86:58: stream
[08-12-19 19:33:57.2039] Slim::Player::StreamingController::_Stream (985) Song queue is now 6,5
[08-12-19 19:33:57.2049] Slim::Buttons::Playlist::jump (417) Jumping to song index: 5
[08-12-19 19:33:57.2057] Slim::Buttons::Playlist::browseplaylistindex (593) New playlistindex: 5
[08-12-19 19:33:57.2066] Slim::Player::StreamingController::_setStreamingSt ate (1792) new streaming state STREAMING
[08-12-19 19:33:57.2074] Slim::Player::StreamingController::_eventAction (301) 00:04:20:05:86:58: NextTrackReady - new state PLAYING-STREAMING
[08-12-19 19:33:57.2083] Slim::Player::StreamingController::_eventAction (301) 00:04:20:05:86:58: ReadyToStream - new state PLAYING-STREAMING
[08-12-19 19:33:57.2213] Slim::Display::Display::curLines (501) lines Slim::Buttons::Playlist::lines [Slim::Player::Player::curLines(2289)]
[08-12-19 19:33:57.2240] Slim::Display::Display::curLines (502) {
jive => {
"icon-id" => 2415,
text => ["Now playing", "4th Dimensional Transition"],
type => "song",
},
line => ["Now playing (6 of 10) ", "4th Dimensional Transition"],
overlay => [undef, "\1"],
}
[08-12-19 19:33:57.2256] Slim::Player::Squeezebox::sendFrame (1025) sending squeezebox frame: grfd, length: 562

this continues until...

[08-12-19 19:36:43.3543] Slim::Display::Display::curLines (502) {
jive => {
"icon-id" => 2415,
text => ["Now playing", "4th Dimensional Transition"],
type => "song",
},
line => ["Now playing (6 of 10) ", "4th Dimensional Transition"],
overlay => [undef, "\1"],
}


I don't know if that proves anything (there don't seem to be any actual errors reported) but thought I would post in case it meant something to someone.

Nigel0
2008-12-21, 04:23
If anyone interested this seems to have been an underpowered server (an eee box) running XP (though the music did play with no glitches)- I switched to a different box running linux and seems to be working fine so far.

awy
2008-12-22, 02:29
How long had it been playing for before the display first got out of sync?

In your edited log above, did you get a log line with 'playerStarted' between starting to stream "Peices of What" and before starting to stream the next track?

I'll be really annoyed if this does not work as I tried really hard to make it solid in the new-streaming work.

Alan.

larva_uk
2009-04-06, 10:53
I've seen the same thing twice now (and only had my squeezeboxes since last week). Only about an hour ago I started a playlist on the Boom in the kitchen which played and displayed correctly and when I moved into another room (around 15 minutes later) and started the Duet which is synchronized to the same playlist both the remote and Squeezecentre were showing the previous track information to the one it was actually playing. The track progress bar will patiently wait at 100% for the track to finish if the previous track was shorter than the one that is currently playing.

awy
2009-04-06, 12:33
What version of SC? And can you capture a log at level player.source=info?

Rawkus
2009-04-15, 10:35
same problem here... i didn't have with the old versions...

Version: 7.3.2 - 24695 @ Mon Jan 19 18:36:25 PST 2009

Hostname: DiskStation

IP-Adresse des Servers: 192.168.1.40

Server-HTTP-Portnummer: 9000

Betriebssystem: Linux - DE - utf8

Plattformarchitektur: armv5tejl-linux

Perl-Version: 5.8.8 - armv5tejl-linux-thread-multi

MySQL-Version: 5.0.51

Anzahl erkannte Player: 2

awy
2009-04-15, 22:33
[08-12-19 19:33:57.2083] Slim::Player::StreamingController::_eventAction (301) 00:04:20:05:86:58: ReadyToStream - new state PLAYING-STREAMING
[08-12-19 19:33:57.2213] Slim::Display::Display::curLines (501) lines Slim::Buttons::Playlist::lines [Slim::Player::Player::curLines(2289)]
[08-12-19 19:33:57.2240] Slim::Display::Display::curLines (502) {
jive => {
"icon-id" => 2415,
text => ["Now playing", "4th Dimensional Transition"],
type => "song",
},
line => ["Now playing (6 of 10) ", "4th Dimensional Transition"],
overlay => [undef, "\1"],
}
[08-12-19 19:33:57.2256] Slim::Player::Squeezebox::sendFrame (1025) sending squeezebox frame: grfd, length: 562

this continues until...

[08-12-19 19:36:43.3543] Slim::Display::Display::curLines (502) {
jive => {
"icon-id" => 2415,
text => ["Now playing", "4th Dimensional Transition"],
type => "song",
},
line => ["Now playing (6 of 10) ", "4th Dimensional Transition"],
overlay => [undef, "\1"],
}


I don't know if that proves anything (there don't seem to be any actual errors reported) but thought I would post in case it meant something to someone.

You did not really show enough of the log there to illustrate teh problem. At the point where the new song starts to stream (new state PLAYING-STREAMING), the old song is still playing, and so the display should still be saying that. It will be about another 10 seconds before the new song actually starts to play, and then the display should change.

Rawkus
2009-05-16, 13:08
The problem exists because I'm not able to play AAC format files... And if there is such a title, it will be displayed, but not played.... therefore it doesn't fit.

I'm using Synology NAS...