Re: Does synchronized playback suck or is it just me?

Code:
--------------------
    [22-10-19 09:29:12.1049] Slim::Player::Song::open (362) spotify://track:1snWlbcbgQpJfknoI30DWG
  [22-10-19 09:29:12.1058] Slim::Player::Song::open (386) seek=false time=0 canSeek=2
  [22-10-19 09:29:12.1068] Slim::Player::TranscodingHelper::getConvertCommand2 (493) Matched: spt->flc via: TMPDIR=/var/lib/squeezeboxserver/cache/spotty [spotty-x86_64] --ap-port=12321  -n Squeezebox -c "/var/lib/squeezeboxserver/cache/spotty/1624eda9" --single-track $URL$ --bitrate 320 --disable-discovery --disable-audio-cache $START$ | [flac] -cs --channels=2 --sample-rate=44100 --bps=16 --endian=little --sign=signed --fast --totally-silent --ignore-chunk-sizes -
  [22-10-19 09:29:12.1069] Slim::Player::Song::open (416) Transcoder: streamMode=R, streamformat=flc
  [22-10-19 09:29:12.1074] Slim::Player::TranscodingHelper::tokenizeConvertCommand2 (667) Using command for conversion: TMPDIR=/var/lib/squeezeboxserver/cache/spotty "/var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/Spotty/Bin/i386-linux/spotty-x86_64" --ap-port=12321  -n Squeezebox -c "/var/lib/squeezeboxserver/cache/spotty/1624eda9" --single-track "spotify://track:1snWlbcbgQpJfknoI30DWG" --bitrate 320 --disable-discovery --disable-audio-cache | "/usr/share/squeezeboxserver/Bin/x86_64-linux/flac" -cs --channels=2 --sample-rate=44100 --bps=16 --endian=little --sign=signed --fast --totally-silent --ignore-chunk-sizes - & |
  [22-10-19 09:29:12.1075] Slim::Player::Song::open (578) Tokenized command: TMPDIR=/var/lib/squeezeboxserver/cache/spotty "/var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/Spotty/Bin/i386-linux/spotty-x86_64" --ap-port=12321  -n Squeezebox -c "/var/lib/squeezeboxserver/cache/spotty/1624eda9" --single-track "spotify://track:1snWlbcbgQpJfknoI30DWG" --bitrate 320 --disable-discovery --disable-audio-cache | "/usr/share/squeezeboxserver/Bin/x86_64-linux/flac" -cs --channels=2 --sample-rate=44100 --bps=16 --endian=little --sign=signed --fast --totally-silent --ignore-chunk-sizes - & |
  [22-10-19 09:29:12.1137] Slim::Player::StreamingController::_Stream (1282) 02:00:80:26:52:07: stream
  [22-10-19 09:29:12.1140] Slim::Player::StreamingController::_Stream (1282) b8:27:eb:83:fe:f3: stream
  [22-10-19 09:29:12.1164] Slim::Player::StreamingController::_Stream (1282) b8:27:eb:a1:5e:53: stream
  [22-10-19 09:29:12.1174] Slim::Player::StreamingController::_Stream (1282) b8:27:eb:93:64:93: stream
  [22-10-19 09:29:12.1185] Slim::Player::StreamingController::_Stream (1282) dc:a6:32:a7:49:c2: stream
  [22-10-19 09:29:12.1196] Slim::Player::StreamingController::_Stream (1282) 0c:b8:15:e8:08:a8: stream
  [22-10-19 09:29:12.1207] Slim::Player::StreamingController::_Stream (1319) Song queue is now 8,7
  [22-10-19 09:29:12.1209] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state STREAMING
  [22-10-19 09:29:12.2605] Slim::Player::Source::_readNextChunk (378) end of file or error on socket, song pos: 3053221417
  [22-10-19 09:29:12.2606] Slim::Player::Source::_readNextChunk (383) 02:00:80:26:52:07 mark end of stream
  [22-10-19 09:29:12.2608] Slim::Player::Source::_readNextChunk (383) b8:27:eb:83:fe:f3 mark end of stream
  [22-10-19 09:29:12.2609] Slim::Player::Source::_readNextChunk (383) b8:27:eb:a1:5e:53 mark end of stream
  [22-10-19 09:29:12.2610] Slim::Player::Source::_readNextChunk (383) b8:27:eb:93:64:93 mark end of stream
  [22-10-19 09:29:12.2611] Slim::Player::Source::_readNextChunk (383) dc:a6:32:a7:49:c2 mark end of stream
  [22-10-19 09:29:12.2612] Slim::Player::Source::_readNextChunk (383) 0c:b8:15:e8:08:a8 mark end of stream
  [22-10-19 09:29:12.2614] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state STREAMOUT
  [22-10-19 09:29:12.2699] Slim::Player::StreamingController::playerEndOfStream (2286) dc:a6:32:a7:49:c2
  [22-10-19 09:29:12.2718] Slim::Player::StreamingController::playerReadyToStream (2218) dc:a6:32:a7:49:c2
  [22-10-19 09:29:12.2721] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE
  [22-10-19 09:29:12.2724] Slim::Player::StreamingController::nextsong (889) The next song is number 9, was 8
  [22-10-19 09:29:12.2727] Slim::Player::Song::new (109) index 9 -> spotify://track:1eyzqe2QqGZUmfcPZtrIyt
  [22-10-19 09:29:12.2731] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state TRACKWAIT
  [22-10-19 09:29:12.2733] Slim::Player::Song::getNextSong (222) spotify://track:1eyzqe2QqGZUmfcPZtrIyt
  [22-10-19 09:29:12.2734] Slim::Player::StreamingController::_nextTrackReady (744) 02:00:80:26:52:07: nextTrack will be index 9
  [22-10-19 09:29:12.2737] Slim::Player::StreamingController::playerEndOfStream (2286) b8:27:eb:83:fe:f3
  [22-10-19 09:29:12.2755] Slim::Player::StreamingController::playerReadyToStream (2218) b8:27:eb:83:fe:f3
  [22-10-19 09:29:12.2762] Slim::Player::StreamingController::playerEndOfStream (2286) b8:27:eb:93:64:93
  [22-10-19 09:29:12.3033] Slim::Player::StreamingController::playerReadyToStream (2218) b8:27:eb:93:64:93
  [22-10-19 09:29:12.3198] Slim::Player::StreamingController::playerEndOfStream (2286) 0c:b8:15:e8:08:a8
  [22-10-19 09:29:12.5710] Slim::Player::StreamingController::playerReadyToStream (2218) 0c:b8:15:e8:08:a8
  [22-10-19 09:29:12.6671] Slim::Player::StreamingController::playerEndOfStream (2286) b8:27:eb:a1:5e:53
  [22-10-19 09:29:12.6778] Slim::Player::StreamingController::playerReadyToStream (2218) b8:27:eb:a1:5e:53
  [22-10-19 09:29:12.6780] Slim::Player::StreamingController::_Stream (1204) aborting streaming because songqueue too long: 3
  [22-10-19 09:29:22.0145] Slim::Player::StreamingController::playerStopped (2177) 02:00:80:26:52:07
  [22-10-19 09:29:22.0148] Slim::Player::StreamingController::_setPlayingState (2377) new playing state STOPPED
  [22-10-19 09:29:22.0150] Slim::Player::StreamingController::_Stream (1210) Song queue is now 9,8
  [22-10-19 09:29:22.0151] Slim::Player::StreamingController::_Stream (1213) 02:00:80:26:52:07: preparing to stream song index 9
  [22-10-19 09:29:22.0153] Slim::Player::Song::open (362) spotify://track:1eyzqe2QqGZUmfcPZtrIyt
  [22-10-19 09:29:22.0163] Slim::Player::Song::open (386) seek=false time=0 canSeek=2
  [22-10-19 09:29:22.0172] Slim::Player::TranscodingHelper::getConvertCommand2 (493) Matched: spt->flc via: TMPDIR=/var/lib/squeezeboxserver/cache/spotty [spotty-x86_64] --ap-port=12321  -n Squeezebox -c "/var/lib/squeezeboxserver/cache/spotty/1624eda9" --single-track $URL$ --bitrate 320 --disable-discovery --disable-audio-cache $START$ | [flac] -cs --channels=2 --sample-rate=44100 --bps=16 --endian=little --sign=signed --fast --totally-silent --ignore-chunk-sizes -
  [22-10-19 09:29:22.0173] Slim::Player::Song::open (416) Transcoder: streamMode=R, streamformat=flc
  [22-10-19 09:29:22.0176] Slim::Player::TranscodingHelper::tokenizeConvertCommand2 (667) Using command for conversion: TMPDIR=/var/lib/squeezeboxserver/cache/spotty "/var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/Spotty/Bin/i386-linux/spotty-x86_64" --ap-port=12321  -n Squeezebox -c "/var/lib/squeezeboxserver/cache/spotty/1624eda9" --single-track "spotify://track:1eyzqe2QqGZUmfcPZtrIyt" --bitrate 320 --disable-discovery --disable-audio-cache | "/usr/share/squeezeboxserver/Bin/x86_64-linux/flac" -cs --channels=2 --sample-rate=44100 --bps=16 --endian=little --sign=signed --fast --totally-silent --ignore-chunk-sizes - & |
  [22-10-19 09:29:22.0178] Slim::Player::Song::open (578) Tokenized command: TMPDIR=/var/lib/squeezeboxserver/cache/spotty "/var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/Spotty/Bin/i386-linux/spotty-x86_64" --ap-port=12321  -n Squeezebox -c "/var/lib/squeezeboxserver/cache/spotty/1624eda9" --single-track "spotify://track:1eyzqe2QqGZUmfcPZtrIyt" --bitrate 320 --disable-discovery --disable-audio-cache | "/usr/share/squeezeboxserver/Bin/x86_64-linux/flac" -cs --channels=2 --sample-rate=44100 --bps=16 --endian=little --sign=signed --fast --totally-silent --ignore-chunk-sizes - & |
  [22-10-19 09:29:22.0237] Slim::Player::StreamingController::_Stream (1282) 02:00:80:26:52:07: stream
  [22-10-19 09:29:22.0242] Slim::Player::StreamingController::_Stream (1282) b8:27:eb:83:fe:f3: stream
  [22-10-19 09:29:22.0264] Slim::Player::StreamingController::_Stream (1282) b8:27:eb:a1:5e:53: stream
  [22-10-19 09:29:22.0275] Slim::Player::StreamingController::_Stream (1282) b8:27:eb:93:64:93: stream
  [22-10-19 09:29:22.0285] Slim::Player::StreamingController::_Stream (1282) dc:a6:32:a7:49:c2: stream
  [22-10-19 09:29:22.0298] Slim::Player::StreamingController::_Stream (1282) 0c:b8:15:e8:08:a8: stream
  [22-10-19 09:29:22.0308] Slim::Player::StreamingController::_Stream (1319) Song queue is now 9,8
  [22-10-19 09:29:22.0311] Slim::Player::StreamingController::_setPlayingState (2377) new playing state BUFFERING
  [22-10-19 09:29:22.0312] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state STREAMING
--------------------


Here is a log excerpt of it going totally haywire.  It's not a client
dropping out in this case, but it was Spotify.  The general consensus
around here seems to be the Spotty spotify plugin lives up to its name,
but I get this kind of behavior even with other streaming sources.


------------------------------------------------------------------------
snubber's Profile: http://forums.slimdevices.com/member.php?userid=73082
View this thread: http://forums.slimdevices.com/showthread.php?t=116953