PDA

View Full Version : songs restarting?



wilgartw
2008-09-09, 05:39
i have an issue where random songs restart. i have turned on logging and get the following warnings, can anyone help me with what they mean?


[08-09-09 13:38:15.3924] Slim::Player::Source::gotoNext (1355) Opening next song...
[08-09-09 13:38:15.3942] Slim::Player::Source::nextsong (1705) The next song is number 37, was 36
[08-09-09 13:38:15.4097] Slim::Player::TranscodingHelper::getConvertCommand (305) Matched Format: mp3 Type: flc Command: [flac] -dcs --skip=$START$ --until=$END$ -- $FILE$ | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ - -
[08-09-09 13:38:15.4112] Slim::Player::Source::gotoNext (1420) Playing out before starting next song. (old format: mp3, new: mp3)
[08-09-09 13:38:15.4125] Slim::Player::Source::playmode (309) 00:04:20:16:58:da: Switching to mode playout-play from play
[08-09-09 13:38:15.4152] Slim::Player::Source::playmode (412) 00:04:20:16:58:da New play mode: playout-play
[08-09-09 13:38:15.4187] Slim::Player::Source::playmode (581) 00:04:20:16:58:da: Current playmode: playout-play

09-09 13:38:15.4200] Slim::Player::Source::dropStreamingConnection (1464) No pending chunks - we're dropping the streaming connection
[08-09-09 13:38:15.4225] Slim::Player::Source::readNextChunk (2591) 00:04:20:16:58:da: Can't opennext, returning no chunk.
[08-09-09 13:41:35.6207] Slim::Player::Source::decoderUnderrun (607) 00:04:20:16:58:da: Decoder underrun while this mode: playout-play
[08-09-09 13:41:35.6222] Slim::Player::Source::nextsong (1705) The next song is number 37, was 36
[08-09-09 13:41:35.6241] Slim::Player::Source::nextsong (1705) The next song is number 37, was 36
[08-09-09 13:41:35.6259] Slim::Player::Source::nextsong (1705) The next song is number 37, was 36
[08-09-09 13:41:35.6277] Slim::Player::Source::skipahead (961) **skipahead: opening next song
[08-09-09 13:41:35.6290] Slim::Player::Source::gotoNext (1355) Opening next song...
[08-09-09 13:41:35.6305] Slim::Player::Source::nextsong (1705) The next song is number 37, was 36
[08-09-09 13:41:35.6454] Slim::Player::TranscodingHelper::getConvertCommand (305) Matched Format: mp3 Type: flc Command: [flac] -dcs --skip=$START$ --until=$END$ -- $FILE$ | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ - -
[08-09-09 13:41:35.6468] Slim::Player::Source::gotoNext (1442) opening next song (old format: mp3, new: mp3) current playmode: playout-play
[08-09-09 13:41:35.6481] Slim::Player::Source::streamingSongIndex (1499) Adding song index 37 to song queue
[08-09-09 13:41:35.6494] Slim::Player::Source::streamingSongIndex (1520) Song queue is now 37,36
[08-09-09 13:41:35.6507] Slim::Player::Source::skipahead (967) **skipahead: restarting
[08-09-09 13:41:35.6520] Slim::Player::Source::playmode (309) 00:04:20:16:58:da: Switching to mode play from playout-play
[08-09-09 13:41:35.6538] Slim::Player::Source::resetSong (1744) Resetting song buffer.
[08-09-09 13:41:35.6571] Slim::Player::Source::openSong (1845) Trying to open: file:///media/music/flac/Diamond,%20Neil/Gold/19.%20He%20Ain%27t%20Heavy,%20He%27s%20My%20Brothe r.flac
[08-09-09 13:41:35.6614] Slim::Player::Source::openSong (2054) duration: [250.84] size: [27938351] endian [] offset: [0] for file:///media/music/flac/Diamond,%20Neil/Gold/19.%20He%20Ain%27t%20Heavy,%20He%27s%20My%20Brothe r.flac
[08-09-09 13:41:35.6705] Slim::Player::TranscodingHelper::getConvertCommand (305) Matched Format: mp3 Type: flc Command: [flac] -dcs --skip=$START$ --until=$END$ -- $FILE$ | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ - -
[08-09-09 13:41:35.6718] Slim::Player::Source::openSong (2083) This is an flc file: file:///media/music/flac/Diamond,%20Neil/Gold/19.%20He%20Ain%27t%20Heavy,%20He%27s%20My%20Brothe r.flac
[08-09-09 13:41:35.6731] Slim::Player::Source::openSong (2084) file type: flc format: mp3 inrate: 889.177 maxRate: 128
[08-09-09 13:41:35.6743] Slim::Player::Source::openSong (2085) command: [flac] -dcs --skip=$START$ --until=$END$ -- $FILE$ | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ - -
[08-09-09 13:41:35.6756] Slim::Player::Source::openSong (2104) Streaming with format: mp3
[08-09-09 13:41:35.7341] Slim::Player::Source::playmode (412) 00:04:20:16:58:da New play mode: play
[08-09-09 13:41:35.7624] Slim::Player::Source::playmode (581) 00:04:20:16:58:da: Current playmode: play
[08-09-09 13:41:45.6236] Slim::Player::Source::trackStartEvent (1610) Got a track starting event
[08-09-09 13:41:45.6249] Slim::Player::Source::trackStartEvent (1617) Song 36 had already started, so it's not longer in the queue
[08-09-09 13:41:45.6261] Slim::Player::Source::trackStartEvent (1626) Song 37 has now started playing
[08-09-09 13:41:45.6309] Slim::Player::Source::trackStartEvent (1654) Song queue is now 37
[08-09-09 13:42:12.8265] Slim::Player::Source::playmode (309) 00:04:20:16:58:da: Switching to mode stop from play
[08-09-09 13:42:12.8296] Slim::Player::Source::playmode (412) 00:04:20:16:58:da New play mode: stop
[08-09-09 13:42:12.8309] Slim::Player::Source::playmode (461) 00:04:20:16:58:da: Stopping and clearing out old chunks
[08-09-09 13:42:12.8354] Slim::Player::Source::resetSong (1744) Resetting song buffer.
[08-09-09 13:42:12.8309] Slim::Player::Source::playmode (461) 00:04:20:16:58:da: Stopping and clearing out old chunks
[08-09-09 13:42:12.8354] Slim::Player::Source::resetSong (1744) Resetting song buffer.
[08-09-09 13:42:12.8368] Slim::Player::Source::resetSongQueue (1573) Resetting song queue
[08-09-09 13:42:12.8383] Slim::Player::Source::resetSongQueue (1583) Song queue is now 37
[08-09-09 13:42:12.8401] Slim::Player::Source::playmode (581) 00:04:20:16:58:da: Current playmode: stop
[08-09-09 13:42:12.8414] Slim::Player::Squeezebox::reconnect (94) 00:04:20:16:58:da restaring play on pseudo-reconnect at 0 bytes
[08-09-09 13:42:12.8427] Slim::Player::Source::playmode (309) 00:04:20:16:58:da: Switching to mode play from stop
[08-09-09 13:42:12.8446] Slim::Player::Source::resetSong (1744) Resetting song buffer.
[08-09-09 13:42:12.8479] Slim::Player::Source::openSong (1845) Trying to open: file:///media/music/flac/Diamond,%20Neil/Gold/19.%20He%20Ain%27t%20Heavy,%20He%27s%20My%20Brothe r.flac
[08-09-09 13:42:12.8522] Slim::Player::Source::openSong (2054) duration: [250.84] size: [27938351] endian [] offset: [0] for file:///media/music/flac/Diamond,%20Neil/Gold/19.%20He%20Ain%27t%20Heavy,%20He%27s%20My%20Brothe r.flac
[08-09-09 13:42:12.8615] Slim::Player::TranscodingHelper::getConvertCommand (305) Matched Format: mp3 Type: flc Command: [flac] -dcs --skip=$START$ --until=$END$ -- $FILE$ | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ - -
[08-09-09 13:42:12.8628] Slim::Player::Source::openSong (2083) This is an flc file: file:///media/music/flac/Diamond,%20Neil/Gold/19.%20He%20Ain%27t%20Heavy,%20He%27s%20My%20Brothe r.flac
[08-09-09 13:42:12.8640] Slim::Player::Source::openSong (2084) file type: flc format: mp3 inrate: 889.177 maxRate: 128
[08-09-09 13:42:12.8653] Slim::Player::Source::openSong (2085) command: [flac] -dcs --skip=$START$ --until=$END$ -- $FILE$ | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ - -
[08-09-09 13:42:12.8665] Slim::Player::Source::openSong (2104) Streaming with format: mp3
[08-09-09 13:42:12.9252] Slim::Player::Source::playmode (412) 00:04:20:16:58:da New play mode: play
[08-09-09 13:42:12.9513] Slim::Player::Source::playmode (581) 00:04:20:16:58:da: Current playmode: play
[08-09-09 13:42:15.2948] Slim::Player::Source::trackStartEvent (1610) Got a track starting event
[08-09-09 13:42:15.2961] Slim::Player::Source::trackStartEvent (1626) Song 37 has now started playing
[08-09-09 13:42:15.2996] Slim::Player::Source::trackStartEvent (1654) Song queue is now 37
[08-09-09 13:42:37.6784] Slim::Utils::Misc::msg (1398) Warning: [13:42:37.6770] Use of uninitialized value in concatenation (.) or string at /usr/share/perl5/Slim/Player/Source.pm line 2533.
[08-09-09 13:42:37.6797] Slim::Player::Source::readNextChunk (2532) end of file or error on socket, opening next song, (song pos: 3690061(tell says: . ), totalbytes: 4013440)
[08-09-09 13:42:37.6810] Slim::Player::Source::gotoNext (1355) Opening next song...
[08-09-09 13:42:37.6828] Slim::Player::Source::nextsong (1705) The next song is number 38, was 37
[08-09-09 13:42:37.6981] Slim::Player::TranscodingHelper::getConvertCommand (305) Matched Format: mp3 Type: flc Command: [flac] -dcs --skip=$START$ --until=$END$ -- $FILE$ | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ - -
[08-09-09 13:42:37.6995] Slim::Player::Source::gotoNext (1420) Playing out before starting next song. (old format: mp3, new: mp3)
[08-09-09 13:42:37.7008] Slim::Player::Source::playmode (309) 00:04:20:16:58:da: Switching to mode playout-play from play
[08-09-09 13:42:37.7036] Slim::Player::Source::playmode (412) 00:04:20:16:58:da New play mode: playout-play
[08-09-09 13:42:37.7069] Slim::Player::Source::playmode (581) 00:04:20:16:58:da: Current playmode: playout-play
[08-09-09 13:42:37.7084] Slim::Player::Source::dropStreamingConnection (1464) No pending chunks - we're dropping the streaming connection
[08-09-09 13:42:37.7113] Slim::Player::Source::readNextChunk (2591) 00:04:20:16:58:da: Can't opennext, returning no chunk.

wilgartw
2008-09-09, 05:50
13:42:12 is when it reset.

wilgartw
2008-09-10, 01:44
no ideas? can anyone point me in the right direction?

Phil Leigh
2008-09-10, 09:48
what actually happens? - does any of the song play at all - do you get any sound?
Have these tracks EVER played?
If not, I'd suspect either some of your files are corrupt or have DRM(did you rip them yourself and if so how?)

wilgartw
2008-09-10, 16:31
yes they play.

usually they play for 20-30 seconds, then restart, then continue all the way through.

i had one track the other day, that play until about 10 seconds before the end. then restarted. it did this maybe 8 times before i got REALLY fed up and switched it off.