So here is a new failure event, this time with debug logging, as
described in the previous post. Strangely, the word STOP comes up
multiple times, but the player actually only stopped once and that was
at 22:11:10. It only took me a couple of seconds to press play again at
22:11:28.
Code:
--------------------
[20-10-28 22:01:00.6220] Slim::Player::StreamingController::_Stream (1213)
00:04:20:27:da:2d: preparing to stream song index 8
[20-10-28 22:01:00.6221] Slim::Player::Song::open (363)
file:///c/media/Music/c/Wolfgang%20Amadeus%20Mozart/Coronation%20Mass%20[Hinreiner]/04%20Wolfgang%20Amadeus%20Mozart%20-%20Motette%20%27Exsultate%20Jubilate%27%20F-DUR%20KV%20165.flac
[20-10-28 22:01:00.6226] Slim::Player::TranscodingHelper::getConvertCommand2
(445) Matched: flc->flc via: -
[20-10-28 22:01:00.6227] Slim::Player::Song::open (387) seek=false time=0
canSeek=1
[20-10-28 22:01:00.6230] Slim::Player::TranscodingHelper::getConvertCommand2
(445) Matched: flc->flc via: -
[20-10-28 22:01:00.6231] Slim::Player::Song::open (408) Transcoder:
streamMode=I, streamformat=flc
[20-10-28 22:01:00.6232] Slim::Player::Song::open (462) Opening stream (no
direct streaming) using Slim::Player::Protocols::File
[file:///c/media/Music/c/Wolfgang%20Amadeus%20Mozart/Coronation%20Mass%20[Hinreiner]/04%20Wolfgang%20Amadeus%20Mozart%20-%20Motette%20%27Exsultate%20Jubilate%27%20F-DUR%20KV%20165.flac]
[20-10-28 22:01:00.6234] Slim::Player::Protocols::File::open (80) duration:
[933] size: [70864241] endian [] offset: [0] for
file:///c/media/Music/c/Wolfgang%20Amadeus%20Mozart/Coronation%20Mass%20[Hinreiner]/04%20Wolfgang%20Amadeus%20Mozart%20-%20Motette%20%27Exsultate%20Jubilate%27%20F-DUR%20KV%20165.flac
[20-10-28 22:01:00.6235] Slim::Player::Protocols::File::open (97) Opening
file /c/media/Music/c/Wolfgang Amadeus Mozart/Coronation Mass [Hinreiner]/04
Wolfgang Amadeus Mozart - Motette 'Exsultate Jubilate' F-DUR KV 165.flac
[20-10-28 22:01:00.6242] Slim::Player::Protocols::File::open (191) Seeking in
0 into /c/media/Music/c/Wolfgang Amadeus Mozart/Coronation Mass [Hinreiner]/04
Wolfgang Amadeus Mozart - Motette 'Exsultate Jubilate' F-DUR KV 165.flac
[20-10-28 22:01:00.6245] Slim::Player::Song::open (483) URL is a song
(audio):
file:///c/media/Music/c/Wolfgang%20Amadeus%20Mozart/Coronation%20Mass%20[Hinreiner]/04%20Wolfgang%20Amadeus%20Mozart%20-%20Motette%20%27Exsultate%20Jubilate%27%20F-DUR%20KV%20165.flac,
type=flc
[20-10-28 22:01:00.6557] Slim::Player::StreamingController::_Stream (1282)
00:04:20:27:da:2d: stream
[20-10-28 22:01:00.6578] Slim::Player::StreamingController::_Stream (1319)
Song queue is now 8,7
[20-10-28 22:01:00.6579]
Slim::Player::StreamingController::_setStreamingState (2386) new streaming
state STREAMING
[20-10-28 22:01:10.0051]
Slim::Player::StreamingController::playerTrackStarted (2200) 00:04:20:27:da:2d
[20-10-28 22:01:10.0054] Slim::Player::StreamingController::_setPlayingState
(2377) new playing state PLAYING
[20-10-28 22:01:10.0055] Slim::Player::StreamingController::_Playing (362)
Song 7 is not longer in the queue
[20-10-28 22:01:10.0056] Slim::Player::StreamingController::_Playing (368)
Song 8 has now started playing
[20-10-28 22:01:10.0060] Slim::Player::StreamingController::_Playing (397)
Song queue is now 8
[20-10-28 22:01:10.4548] Slim::Player::TranscodingHelper::getConvertCommand2
(445) Matched: flc->flc via: -
[20-10-28 22:11:10.0102] Slim::Player::StreamingController::stop (2117)
00:04:20:27:da:2d
[20-10-28 22:11:10.0109] Slim::Player::StreamingController::_Stop (610) Song
queue is now 8
[20-10-28 22:11:10.0133] Slim::Player::StreamingController::_setPlayingState
(2377) new playing state STOPPED
[20-10-28 22:11:10.0135]
Slim::Player::StreamingController::_setStreamingState (2386) new streaming
state IDLE
[20-10-28 22:11:10.0139] Slim::Player::Source::playmode (95)
00:04:20:27:da:2d: Current playmode: stop
[20-10-28 22:11:10.5536] Slim::Player::TranscodingHelper::getConvertCommand2
(445) Matched: flc->flc via: -
[20-10-28 22:11:28.6430] Slim::Player::StreamingController::playerActive
(2013) 00:04:20:27:da:2d player already active: 00:04:20:27:da:2d
[20-10-28 22:11:28.6435] Slim::Player::StreamingController::play (2120)
00:04:20:27:da:2d
[20-10-28 22:11:28.6439] Slim::Player::StreamingController::_Stop (610) Song
queue is now 8
[20-10-28 22:11:28.6440] Slim::Player::StreamingController::_setPlayingState
(2377) new playing state STOPPED
[20-10-28 22:11:28.6441]
Slim::Player::StreamingController::_setStreamingState (2386) new streaming
state IDLE
[20-10-28 22:11:28.6447] Slim::Player::Song::new (107) index 8 ->
file:///c/media/Music/c/Wolfgang%20Amadeus%20Mozart/Coronation%20Mass%20[Hinreiner]/04%20Wolfgang%20Amadeus%20Mozart%20-%20Motette%20%27Exsultate%20Jubilate%27%20F-DUR%20KV%20165.flac
[20-10-28 22:11:28.6449]
Slim::Player::StreamingController::_setStreamingState (2386) new streaming
state TRACKWAIT
[20-10-28 22:11:28.6461] Slim::Player::StreamingController::_playersMessage
(796) Now Playing:
file:///c/media/Music/c/Wolfgang%20Amadeus%20Mozart/Coronation%20Mass%20[Hinreiner]/04%20Wolfgang%20Amadeus%20Mozart%20-%20Motette%20%27Exsultate%20Jubilate%27%20F-DUR%20KV%20165.flac
[20-10-28 22:11:28.6491] Slim::Player::Song::getNextSong (228)
file:///c/media/Music/c/Wolfgang%20Amadeus%20Mozart/Coronation%20Mass%20[Hinreiner]/04%20Wolfgang%20Amadeus%20Mozart%20-%20Motette%20%27Exsultate%20Jubilate%27%20F-DUR%20KV%20165.flac
[20-10-28 22:11:28.6493] Slim::Player::StreamingController::_nextTrackReady
(744) 00:04:20:27:da:2d: nextTrack will be index 8
[20-10-28 22:11:28.6494] Slim::Player::StreamingController::_Stream (1210)
Song queue is now 8
[20-10-28 22:11:28.6495] Slim::Player::StreamingController::_Stream (1213)
00:04:20:27:da:2d: preparing to stream song index 8
[20-10-28 22:11:28.6496] Slim::Player::Song::open (363)
file:///c/media/Music/c/Wolfgang%20Amadeus%20Mozart/Coronation%20Mass%20[Hinreiner]/04%20Wolfgang%20Amadeus%20Mozart%20-%20Motette%20%27Exsultate%20Jubilate%27%20F-DUR%20KV%20165.flac
[20-10-28 22:11:28.6501] Slim::Player::TranscodingHelper::getConvertCommand2
(445) Matched: flc->flc via: -
[20-10-28 22:11:28.6502] Slim::Player::Song::open (387) seek=false time=0
canSeek=1
[20-10-28 22:11:28.6505] Slim::Player::TranscodingHelper::getConvertCommand2
(445) Matched: flc->flc via: -
[20-10-28 22:11:28.6506] Slim::Player::Song::open (408) Transcoder:
streamMode=I, streamformat=flc
[20-10-28 22:11:28.6507] Slim::Player::Song::open (462) Opening stream (no
direct streaming) using Slim::Player::Protocols::File
[file:///c/media/Music/c/Wolfgang%20Amadeus%20Mozart/Coronation%20Mass%20[Hinreiner]/04%20Wolfgang%20Amadeus%20Mozart%20-%20Motette%20%27Exsultate%20Jubilate%27%20F-DUR%20KV%20165.flac]
[20-10-28 22:11:28.6510] Slim::Player::Protocols::File::open (80) duration:
[933] size: [70864241] endian [] offset: [0] for
file:///c/media/Music/c/Wolfgang%20Amadeus%20Mozart/Coronation%20Mass%20[Hinreiner]/04%20Wolfgang%20Amadeus%20Mozart%20-%20Motette%20%27Exsultate%20Jubilate%27%20F-DUR%20KV%20165.flac
[20-10-28 22:11:28.6511] Slim::Player::Protocols::File::open (97) Opening
file /c/media/Music/c/Wolfgang Amadeus Mozart/Coronation Mass [Hinreiner]/04
Wolfgang Amadeus Mozart - Motette 'Exsultate Jubilate' F-DUR KV 165.flac
[20-10-28 22:11:28.6513] Slim::Player::Protocols::File::open (191) Seeking in
0 into /c/media/Music/c/Wolfgang Amadeus Mozart/Coronation Mass [Hinreiner]/04
Wolfgang Amadeus Mozart - Motette 'Exsultate Jubilate' F-DUR KV 165.flac
[20-10-28 22:11:28.6515] Slim::Player::Song::open (483) URL is a song
(audio):
file:///c/media/Music/c/Wolfgang%20Amadeus%20Mozart/Coronation%20Mass%20[Hinreiner]/04%20Wolfgang%20Amadeus%20Mozart%20-%20Motette%20%27Exsultate%20Jubilate%27%20F-DUR%20KV%20165.flac,
type=flc
[20-10-28 22:11:28.6877] Slim::Player::StreamingController::_Stream (1282)
00:04:20:27:da:2d: stream
[20-10-28 22:11:28.6887] Slim::Player::StreamingController::_Stream (1319)
Song queue is now 8
[20-10-28 22:11:28.6890] Slim::Player::StreamingController::_setPlayingState
(2377) new playing state BUFFERING
[20-10-28 22:11:28.6891]
Slim::Player::StreamingController::_setStreamingState (2386) new streaming
state STREAMING
[20-10-28 22:11:28.6977] Slim::Player::TranscodingHelper::getConvertCommand2
(445) Matched: flc->flc via: -
[20-10-28 22:11:30.1574]
Slim::Player::StreamingController::playerTrackStarted (2200) 00:04:20:27:da:2d
[20-10-28 22:11:30.1576] Slim::Player::StreamingController::_setPlayingState
(2377) new playing state PLAYING
[20-10-28 22:11:30.1577] Slim::Player::StreamingController::_Playing (368)
Song 8 has now started playing
[20-10-28 22:11:30.1581] Slim::Player::StreamingController::_Playing (397)
Song queue is now 8
--------------------
I guess the central log-item is
Code:
--------------------
[20-10-28 22:11:10.0102] Slim::Player::StreamingController::stop (2117)
00:04:20:27:da:2d
--------------------
What does the 2117 indicate? It seems to be the only bit of information
that could lead us somewhere...
*Server*: LMS 7.9.2 on an Ubuntu 18.04 virtual machine
*Players*: Radio, Touch, Duet (Receiver & Controller), 2 Booms
*Remote control apps*: SqueezePad (iOS), Squeezecontroller (Android)
*Important plugins*: Trackstat, Spicefly Sugarcube, Lazy Search Music,
Custom Browse, Multi Library, Material Skin
------------------------------------------------------------------------
chaug's Profile: http://forums.slimdevices.com/member.php?userid=47641
View this thread: http://forums.slimdevices.com/showthread.php?t=113126
_______________________________________________
Radio mailing list
[email protected]
http://lists.slimdevices.com/mailman/listinfo/radio
