Thanks for the suggestion. I have set player.source logging to Info. Below extract shows song successfully playing at 17.39 using the default convert.conf. I then stopped the LMS server, added the custom-convert.conf, restarted it and tried to play the same song at 17.44. For some reason it then fails to start streaming ...
[21-02-13 17:37:45.4451] main::init (388) Starting Logitech Media Server (v8.0.0, 1605892076, Fri Nov 20 18:28:44 CET 2020) perl 5.024000 - armle-linux [21-02-13 17:39:30.4790] Slim::Player::StreamingController::jumpToTime (2166) 00:04:20:07:0b:98 [21-02-13 17:39:30.4802] Slim::Player::StreamingController::_Stop (610) Song queue is now 2 [21-02-13 17:39:30.4816] Slim::Player::StreamingController::_setPlayingState (2377) new playing state STOPPED [21-02-13 17:39:30.4821] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE [21-02-13 17:39:30.4826] Slim::Player::StreamingController::_Stream (1136) 00:04:20:07:0b:98: got song from params, song index 2 [21-02-13 17:39:30.4831] Slim::Player::StreamingController::_Stream (1210) Song queue is now 2 [21-02-13 17:39:30.4835] Slim::Player::StreamingController::_Stream (1213) 00:04:20:07:0b:98: preparing to stream song index 2 [21-02-13 17:39:30.4840] Slim::Player::Song:: open (367) wimp://96206243.flac [21-02-13 17:39:30.4848] Slim::Player::Song:: open (391) seek=false time=0 canSeek=1 [21-02-13 17:39:30.4858] Slim::Player::TranscodingHelper::getConvertCommand2 (470) Matched: flc->flc via: - [21-02-13 17:39:30.4863] Slim::Player::Song:: open (412) Transcoder: streamMode=I, streamformat=flc [21-02-13 17:39:30.4869] Slim::Player::Song:: open (468) Opening stream (no direct streaming) using Slim::Plugin::WiMP::ProtocolHandler [wimp://96206243.flac] [21-02-13 17:39:30.6137] Slim::Player::Song:: open (489) URL is a song (audio): wimp://96206243.flac, type=flc [21-02-13 17:39:30.6155] Slim::Player::StreamingController::_Stream (1282) 00:04:20:07:0b:98: stream [21-02-13 17:39:30.6178] Slim::Player::StreamingController::_Stream (1319) Song queue is now 2 [21-02-13 17:39:30.6185] Slim::Player::StreamingController::_setPlayingState (2377) new playing state BUFFERING [21-02-13 17:39:30.6190] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state STREAMING [21-02-13 17:39:30.7423] Slim::Player::Player::_buffering (1144) Buffering... 0 / 245760 [21-02-13 17:39:30.7429] Slim::Player::Player::_buffering (1145) +output... 3500800 / 1058400 [21-02-13 17:39:30.9007] Slim::Player::StreamingController:: playerTrackStarted (2200) 00:04:20:07:0b:98 [21-02-13 17:39:30.9013] Slim::Player::StreamingController::_setPlayingState (2377) new playing state PLAYING [21-02-13 17:39:30.9018] Slim::Player::StreamingController::_Playing (368) Song 2 has now started playing [21-02-13 17:39:30.9030] Slim::Player::StreamingController::_Playing (397) Song queue is now 2 [21-02-13 17:42:38.9482] Slim::Player::StreamingController:: pause (2136) 00:04:20:07:0b:98 [21-02-13 17:42:38.9490] Slim::Player::StreamingController::_setPlayingState (2377) new playing state PAUSED [21-02-13 17:42:38.9511] Slim::Player::Source:: playmode (95) 00:04:20:07:0b:98: Current playmode: pause [21-02-13 17:42:38.9567] Slim::Player::StreamingController::_CheckPaused (438) Stopping remote stream upon full buffer when paused [21-02-13 17:42:38.9588] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE [21-02-13 17:42:56.6481] Slim::Utils::Misc::msg (1266) Warning: [17:42:56.6474] Argument "" isn't numeric in numeric gt (>) at /volume1/@appstore/SqueezeCenter/Slim/Plugin/AudioScrobbler/HTML/EN/plugins/AudioScrobbler/settings/basic.html line 6. [21-02-13 17:43:27.7053] main::init (388) Starting Logitech Media Server (v8.0.0, 1605892076, Fri Nov 20 18:28:44 CET 2020) perl 5.024000 - armle-linux [21-02-13 17:44:21.6541] Slim::Player::StreamingController:: play (2120) 00:04:20:07:0b:98 [21-02-13 17:44:21.6560] Slim::Player::StreamingController::_Stop (610) Song queue is now 2 [21-02-13 17:44:21.6569] Slim::Player::StreamingController::_setPlayingState (2377) new playing state STOPPED [21-02-13 17:44:21.6577] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE [21-02-13 17:44:21.6591] Slim::Player::Song::new (108) index 2 -> wimp://96206243.flac [21-02-13 17:44:21.6605] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state TRACKWAIT [21-02-13 17:44:21.6629] Slim::Player::StreamingController::_playersMessage (796) Getting stream info...: wimp://96206243.flac [21-02-13 17:44:21.6666] Slim::Player::Song::getNextSong (230) wimp://96206243.flac [21-02-13 17:44:21.6677] Slim::Player::Song::getNextSong (252) scanning URL wimp://96206243.flac [21-02-13 17:44:21.6689] Slim::Player::Song::getNextSong (230) wimp://96206243.flac [21-02-13 17:44:22.3180] Slim::Player::StreamingController::_nextTrackReady (744) 00:04:20:07:0b:98: nextTrack will be index 2 [21-02-13 17:44:22.3187] Slim::Player::StreamingController::_Stream (1210) Song queue is now 2 [21-02-13 17:44:22.3192] Slim::Player::StreamingController::_Stream (1213) 00:04:20:07:0b:98: preparing to stream song index 2 [21-02-13 17:44:22.3197] Slim::Player::Song:: open (367) wimp://96206243.flac [21-02-13 17:44:22.3203] Slim::Player::Song:: open (391) seek=false time=0 canSeek=1 [21-02-13 17:44:22.3215] Slim::Player::TranscodingHelper::getConvertCommand2 (470) Matched: flc->flc via: [sox] -q -t flac $FILE$ -t flac - [21-02-13 17:44:22.3219] Slim::Player::Song:: open (412) Transcoder: streamMode=I, streamformat=flc [21-02-13 17:44:22.3224] Slim::Player::Song:: open (468) Opening stream (no direct streaming) using Slim::Plugin::WiMP::ProtocolHandler [wimp://96206243.flac] [21-02-13 17:44:22.4470] Slim::Player::Song:: open (489) URL is a song (audio): wimp://96206243.flac, type=flc [21-02-13 17:44:22.4482] Slim::Player::TranscodingHelper::tokenizeConvertCommand2 (641) Using command for conversion: "/volume1/@appstore/SqueezeCenter/Bin/sox" -q -t flac - -t flac - [21-02-13 17:44:22.4487] Slim::Player::Song:: open (565) Tokenized command: "/volume1/@appstore/SqueezeCenter/Bin/sox" -q -t flac - -t flac - [21-02-13 17:44:22.4716] Slim::Player::StreamingController::_Stream (1282) 00:04:20:07:0b:98: stream [21-02-13 17:44:22.4745] Slim::Player::StreamingController::_Stream (1319) Song queue is now 2 [21-02-13 17:44:22.4752] Slim::Player::StreamingController::_setPlayingState (2377) new playing state BUFFERING [21-02-13 17:44:22.4757] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state STREAMING [21-02-13 17:44:22.5640] Slim::Player::Source::_readNextChunk (372) end of file or error on socket, song pos: 0 [21-02-13 17:44:22.5646] Slim::Player::Source::_readNextChunk (377) 00:04:20:07:0b:98 mark end of stream [21-02-13 17:44:22.5651] Slim::Player::Source::_readNextChunk (385) Didn't stream any bytes for this song; mark it as failed [21-02-13 17:44:22.5656] Slim::Player::StreamingController:: playerStreamingFailed (2248) 00:04:20:07:0b:98 [21-02-13 17:44:22.5667] Slim::Player::StreamingController::_playersMessage (796) Problem: Can't open file for:: wimp://96206243.flac [21-02-13 17:44:22.5716] Slim::Player::StreamingController::_Stop (610) Song queue is now 2 [21-02-13 17:44:22.5732] Slim::Player::StreamingController::_setPlayingState (2377) new playing state STOPPED [21-02-13 17:44:22.5738] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE [21-02-13 17:44:22.5746] Slim::Player::StreamingController::_willRetry (1408) no retry data ------------------------------------------------------------------------ lucifer-chops's Profile: http://forums.slimdevices.com/member.php?userid=70415 View this thread: http://forums.slimdevices.com/showthread.php?t=113932 _______________________________________________ plugins mailing list plugins@lists.slimdevices.com http://lists.slimdevices.com/mailman/listinfo/plugins
