Hi again,
Now I have access to the system and logs.
I have enabled info logging for player.source, player.streaming,
player.streaming.direct and plugin.C3PO
First, here's what succesful playback looks like:
Here's the server log of a successful DASH-flac playback, with C-3PO
disabled:
Code:
--------------------
[17-08-11 20:50:00.3366] Slim::Player::StreamingController::resume (2136)
fe:c6:a1:a2:3a:bffadein=undef
[17-08-11 20:50:00.3379] Slim::Player::StreamingController::_Stop (603) Song
queue is now 0
[17-08-11 20:50:00.3381] Slim::Player::StreamingController::_setPlayingState
(2357) new playing state STOPPED
[17-08-11 20:50:00.3384]
Slim::Player::StreamingController::_setStreamingState (2366) new streaming
state IDLE
[17-08-11 20:50:00.3386] Slim::Player::StreamingController::_Stream (1129)
fe:c6:a1:a2:3a:bf: got song from params, song index 0
[17-08-11 20:50:00.3388] Slim::Player::StreamingController::_Stream (1203)
Song queue is now 0
[17-08-11 20:50:00.3390] Slim::Player::StreamingController::_Stream (1206)
fe:c6:a1:a2:3a:bf: preparing to stream song index 0
[17-08-11 20:50:00.3392] Slim::Player::Song::open (363)
dashflac://vs-dash-ww-rd-live.bbcfmt.hs.llnwd.net/al/lossless/client_manifest.mpd|
[17-08-11 20:50:00.3395] Slim::Player::Song::open (387) seek=false time=0
canSeek=1
[17-08-11 20:50:00.3400] Slim::Player::TranscodingHelper::getConvertCommand2
(446) Matched: flc->flc via: -
[17-08-11 20:50:00.3402] Slim::Player::Song::open (408) Transcoder:
streamMode=I, streamformat=flc
[17-08-11 20:50:00.3405] Slim::Player::Song::open (461) Opening stream (no
direct streaming) using Plugins::BBCiPlayer::DASH
[dashflac://vs-dash-ww-rd-live.bbcfmt.hs.llnwd.net/al/lossless/client_manifest.mpd|]
[17-08-11 20:50:00.3412] Slim::Player::Song::open (482) URL is a song
(audio):
dashflac://vs-dash-ww-rd-live.bbcfmt.hs.llnwd.net/al/lossless/client_manifest.mpd|,
type=flc
[17-08-11 20:50:00.3420] Slim::Player::StreamingController::_Stream (1265)
fe:c6:a1:a2:3a:bf: stream
[17-08-11 20:50:00.3429] Slim::Player::StreamingController::_Stream (1302)
Song queue is now 0
[17-08-11 20:50:00.3432] Slim::Player::StreamingController::_setPlayingState
(2357) new playing state BUFFERING
[17-08-11 20:50:00.3434]
Slim::Player::StreamingController::_setStreamingState (2366) new streaming
state STREAMING
[17-08-11 20:50:00.3439] Slim::Player::Source::playmode (96)
fe:c6:a1:a2:3a:bf: Current playmode: play
[17-08-11 20:50:00.4429] Slim::Player::Squeezebox2::directHeaders (471)
Processing headers for direct streaming:
HTTP/1.1 200 OK
Server: Logitech Media Server (7.9.1 - 1501791870)
Connection: close
Content-Type: audio/x-flac
[17-08-11 20:50:00.4681] Slim::Player::Player::_buffering (1140) Buffering...
0 / 261120
[17-08-11 20:50:00.7702] Slim::Player::Player::_buffering (1140) Buffering...
0 / 261120
[17-08-11 20:50:01.0717] Slim::Player::Player::_buffering (1140) Buffering...
0 / 261120
[17-08-11 20:50:01.2574] Slim::Player::StreamingController::playerBufferReady
(2258) fe:c6:a1:a2:3a:bf
[17-08-11 20:50:01.2575] Slim::Player::StreamingController::_setPlayingState
(2357) new playing state WAITING_TO_SYNC
[17-08-11 20:50:01.2579] Slim::Player::StreamingController::_setPlayingState
(2357) new playing state PLAYING
[17-08-11 20:50:01.2580] Slim::Player::StreamingController::_Playing (361)
Song 0 has now started playing
[17-08-11 20:50:01.2584] Slim::Player::StreamingController::_Playing (390)
Song queue is now 0
[17-08-11 20:50:01.2607] Plugins::C3PO::Plugin::newSong (236) newSong request
received from client fe:c6:a1:a2:3a:bf
[17-08-11 20:50:01.2614] Slim::Player::TranscodingHelper::getConvertCommand2
(446) Matched: flc->flc via: -
[17-08-11 20:50:01.2621] Slim::Utils::Misc::msg (1250) Warning:
[20:50:01.2620] Use of uninitialized value in concatenation (.) or string at
/var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/C3PO/Plugin.pm line
279.
[17-08-11 20:50:01.2623] Plugins::C3PO::Plugin::newSong (308)
At: 2017/08/11 20:50:01
Command:
-
Tokenized command:
[17-08-11 20:50:01.2656] Plugins::IckStreamPlugin::PlayerService::__ANON__
(1495) Error when sending playerStatusChanged for SqueezeLite-R2
[17-08-11 20:50:02.2598]
Slim::Player::StreamingController::playerTrackStarted (2180) fe:c6:a1:a2:3a:bf
[17-08-11 20:50:02.2601] Slim::Player::StreamingController::_setPlayingState
(2357) new playing state PLAYING
[17-08-11 20:50:02.2603] Slim::Player::StreamingController::_Playing (361)
Song 0 has now started playing
[17-08-11 20:50:02.2608] Slim::Player::StreamingController::_Playing (390)
Song queue is now 0
[17-08-11 20:50:02.2635] Plugins::C3PO::Plugin::newSong (236) newSong request
received from client fe:c6:a1:a2:3a:bf
[17-08-11 20:50:02.2650] Slim::Player::TranscodingHelper::getConvertCommand2
(446) Matched: flc->flc via: -
[17-08-11 20:50:02.2672] Slim::Utils::Misc::msg (1250) Warning:
[20:50:02.2668] Use of uninitialized value in concatenation (.) or string at
/var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/C3PO/Plugin.pm line
279.
[17-08-11 20:50:02.2677] Plugins::C3PO::Plugin::newSong (308)
At: 2017/08/11 20:50:02
Command:
-
Tokenized command:
[17-08-11 20:50:02.2702] Plugins::IckStreamPlugin::PlayerService::__ANON__
(1495) Error when sending playerStatusChanged for SqueezeLite-R2
--------------------
and the squeezelite-R2 log with info level logging:
Code:
--------------------
[20:50:00.339674] decode_flush:190 decode flush
[20:50:00.339906] output_flush:423 flush output buffer
[20:50:00.344827] codec_open:218 codec open: 'f'
[20:50:00.344999] stream_sock:384 connecting to 192.168.253.80:9000
[20:50:00.345392] stream_sock:413 header: GET
/stream.mp3?player=fe:c6:a1:a2:3a:bf HTTP/1.0
[20:50:00.443704] stream_thread:176 headers: len: 118
HTTP/1.1 200 OK
Server: Logitech Media Server (7.9.1 - 1501791870)
Connection: close
Content-Type: audio/x-flac
[20:50:01.307734] write_cb:116 setting track_start
[20:50:01.308472] _checkfade:281 fade mode: 2 duration: 0 track-start
[20:50:01.308897] _checkfade:290 fade IN: 0 frames
[20:50:01.314135] _output_frames:146 track start sample rate: 48000
replay_gain: 0
[20:50:01.324608] _output_frames:174 fade start reached
[20:50:01.324825] _output_frames:207 fade complete
[20:50:12.676089] decode_flush:190 decode flush
[20:50:12.676483] output_flush:423 flush output buffer
--------------------
------------------------------------------------------------------------
Learnincurve's Profile: http://forums.slimdevices.com/member.php?userid=64807
View this thread: http://forums.slimdevices.com/showthread.php?t=105309
_______________________________________________
Squeezecenter mailing list
[email protected]
http://lists.slimdevices.com/mailman/listinfo/squeezecenter
