-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2025-12-18 23:41:53 CET. -- Dec 18 23:40:00 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 23:40:04 volumio volumio[835]: info: CALLMETHOD: music_service tidal saveConfig [object Object] Dec 18 23:40:04 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: tidal , saveConfig Dec 18 23:40:04 volumio volumio[835]: info: Saving configuration Dec 18 23:40:04 volumio volumio[835]: info: Done Dec 18 23:40:04 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 23:40:08 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 23:40:11 volumio volumio[835]: info: CoreCommandRouter::volumioPrevious Dec 18 23:40:11 volumio volumio[835]: info: CoreStateMachine::previous Dec 18 23:40:11 volumio volumio[835]: info: [1766097611614] ControllerTidal::seek Dec 18 23:40:11 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:40:11 volumio volumio[835]: info: ControllerMpd::seek Dec 18 23:40:11 volumio volumio[835]: error: null Dec 18 23:40:11 volumio volumio[835]: info: Dec 18 23:40:11 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:11 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:11 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:11 volumio volumio[835]: info: sendMpdCommand status took 1 milliseconds Dec 18 23:40:11 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:11 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:11 volumio volumio[835]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 18 23:40:11 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:11 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:40:11 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:40:11 volumio volumio[835]: info: CorePlayQueue::getTrack 4 Dec 18 23:40:11 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":161,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1427 Kbps","isStreaming":false,"title":"345250732&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/345250732&soundQuality=HI_RES","trackType":"tidal"} Dec 18 23:40:11 volumio volumio[835]: verbose: CURRENT POSITION 4 Dec 18 23:40:11 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:40:11 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Dec 18 23:40:11 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Dec 18 23:40:11 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:11 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:40:11 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:11 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:11 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:11 volumio volumio[835]: info: ------------------------------ 13ms Dec 18 23:40:12 volumio volumio[835]: info: CoreCommandRouter::volumioPrevious Dec 18 23:40:12 volumio volumio[835]: info: CoreStateMachine::previous Dec 18 23:40:12 volumio volumio[835]: info: [1766097612477] ControllerTidal::previous Dec 18 23:40:12 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:40:12 volumio volumio[835]: info: CoreStateMachine::previous Dec 18 23:40:12 volumio volumio[835]: info: CorePlayQueue::getTrack 4 Dec 18 23:40:12 volumio volumio[835]: info: CoreStateMachine::stop Dec 18 23:40:12 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:40:12 volumio volumio[835]: info: CoreStateMachine::stPlaybackTimer Dec 18 23:40:12 volumio volumio[835]: info: CoreStateMachine::updateTrackBlock Dec 18 23:40:12 volumio volumio[835]: info: CorePlayQueue::getTrackBlock Dec 18 23:40:12 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:12 volumio volumio[835]: info: CorePlayQueue::getTrack 4 Dec 18 23:40:12 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:40:12 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:12 volumio volumio[835]: info: CoreStateMachine::serviceStop Dec 18 23:40:12 volumio volumio[835]: info: CorePlayQueue::getTrack 4 Dec 18 23:40:12 volumio volumio[835]: info: CoreCommandRouter::serviceStop Dec 18 23:40:12 volumio volumio[835]: info: [1766097612480] ControllerTidal::stop Dec 18 23:40:12 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:40:12 volumio volumio[835]: info: ControllerMpd::stop Dec 18 23:40:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 23:40:12 volumio volumio[835]: info: Dec 18 23:40:12 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:12 volumio volumio[835]: info: sendMpdCommand stop took 8 milliseconds Dec 18 23:40:12 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:12 volumio volumio[835]: info: sendMpdCommand status took 0 milliseconds Dec 18 23:40:12 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:12 volumio volumio[835]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 18 23:40:12 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:12 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:40:12 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:40:12 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:12 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:40:12 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:12 volumio volumio[835]: info: CorePlayQueue::getTrack 4 Dec 18 23:40:12 volumio volumio[835]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"345250732&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/345250732&soundQuality=HI_RES","trackType":"tidal"} Dec 18 23:40:12 volumio volumio[835]: verbose: CURRENT POSITION 4 Dec 18 23:40:12 volumio volumio[835]: info: CoreStateMachine::syncState stateService stop Dec 18 23:40:12 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus stop Dec 18 23:40:12 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:12 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:12 volumio volumio[835]: info: No code Dec 18 23:40:12 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:12 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:12 volumio volumio[835]: info: ------------------------------ 10ms Dec 18 23:40:12 volumio volumio[835]: info: CoreStateMachine::play index undefined Dec 18 23:40:12 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:40:12 volumio volumio[835]: info: CorePlayQueue::getTrack 36 Dec 18 23:40:12 volumio volumio[835]: info: CoreStateMachine::startPlaybackTimer Dec 18 23:40:12 volumio volumio[835]: info: CorePlayQueue::getTrack 36 Dec 18 23:40:12 volumio volumio[835]: info: [1766097612982] ControllerTidal::clearAddPlayTrack Dec 18 23:40:12 volumio volumio[835]: info: Getting stream with soundQuality LOSSLESS Dec 18 23:40:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 23:40:12 volumio volumio[835]: info: sendMpdCommand stop took 1 milliseconds Dec 18 23:40:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 23:40:12 volumio volumio[835]: info: Dec 18 23:40:12 volumio volumio[835]: ---------------------------- MPD announces system playlist update Dec 18 23:40:12 volumio volumio[835]: info: Ignoring MPD Status Update Dec 18 23:40:12 volumio volumio[835]: info: sendMpdCommand clear took 1 milliseconds Dec 18 23:40:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/476664851&soundQuality=LOSSLESS" Dec 18 23:40:12 volumio volumio[835]: error: updateQueue error: null Dec 18 23:40:12 volumio volumio[835]: info: Dec 18 23:40:12 volumio volumio[835]: ---------------------------- MPD announces system playlist update Dec 18 23:40:12 volumio volumio[835]: info: Ignoring MPD Status Update Dec 18 23:40:12 volumio volumio[835]: info: ------------------------------ 2ms Dec 18 23:40:12 volumio volumio[835]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/476664851&soundQuality=LOSSLESS" took 2 milliseconds Dec 18 23:40:12 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:40:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand play Dec 18 23:40:12 volumio volumio[835]: info: ------------------------------ 2ms Dec 18 23:40:12 volumio volumio[835]: STREAMING PROXY: Handling url /?data=tidal://song/476664851&soundQuality=LOSSLESS Dec 18 23:40:12 volumio volumio[835]: info: sendMpdCommand play took 2 milliseconds Dec 18 23:40:12 volumio volumio[835]: info: Executing endpoint getStreamUrltidal Dec 18 23:40:12 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Dec 18 23:40:13 volumio volumio[835]: info: getStreamUrl took 236 milliseconds Dec 18 23:40:13 volumio volumio[835]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1OTcyMjE4ODBhOWMxMmE1ZDBlNGYyNDhmMWU1YWQ0MS5tcDQ/0.flac?token=1766101213~YjkxYzIzOGY2NDJkMzg5YWNjZjRhOTMwY2E3OGFkOTljYTFjNmQ2NA== Dec 18 23:40:13 volumio volumio[835]: STREAMING PROXY: Response: 200, length: 10508138 Dec 18 23:40:13 volumio volumio[835]: info: Dec 18 23:40:13 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:13 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:13 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:13 volumio volumio[835]: info: Dec 18 23:40:13 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:13 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:13 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:13 volumio volumio[835]: info: sendMpdCommand status took 2 milliseconds Dec 18 23:40:13 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:13 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:13 volumio volumio[835]: info: sendMpdCommand status took 1 milliseconds Dec 18 23:40:13 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:40:13 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:13 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:13 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:13 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:40:13 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:40:13 volumio volumio[835]: info: CorePlayQueue::getTrack 36 Dec 18 23:40:13 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":104,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"476664851&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/476664851&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:40:13 volumio volumio[835]: verbose: CURRENT POSITION 36 Dec 18 23:40:13 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:40:13 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus stop Dec 18 23:40:13 volumio volumio[835]: info: ------------------------------ 5ms Dec 18 23:40:13 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:40:13 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:13 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:40:13 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:40:13 volumio volumio[835]: info: CorePlayQueue::getTrack 36 Dec 18 23:40:13 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":104,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"476664851&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/476664851&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:40:13 volumio volumio[835]: verbose: CURRENT POSITION 36 Dec 18 23:40:13 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:40:13 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Dec 18 23:40:13 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Dec 18 23:40:13 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:13 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:40:13 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:13 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:13 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:13 volumio volumio[835]: info: ------------------------------ 13ms Dec 18 23:40:14 volumio volumio[835]: STREAMING PROXY: Client dropped request, destroying Dec 18 23:40:15 volumio volumio[835]: info: CoreCommandRouter::volumioNext Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::next Dec 18 23:40:15 volumio volumio[835]: info: [1766097615049] ControllerTidal::next Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::next Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::stop Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::stPlaybackTimer Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::updateTrackBlock Dec 18 23:40:15 volumio volumio[835]: info: CorePlayQueue::getTrackBlock Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:15 volumio volumio[835]: info: CorePlayQueue::getTrack 36 Dec 18 23:40:15 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:40:15 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::serviceStop Dec 18 23:40:15 volumio volumio[835]: info: CorePlayQueue::getTrack 36 Dec 18 23:40:15 volumio volumio[835]: info: CoreCommandRouter::serviceStop Dec 18 23:40:15 volumio volumio[835]: info: [1766097615062] ControllerTidal::stop Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:40:15 volumio volumio[835]: info: ControllerMpd::stop Dec 18 23:40:15 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 23:40:15 volumio volumio[835]: info: Dec 18 23:40:15 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:15 volumio volumio[835]: info: sendMpdCommand stop took 7 milliseconds Dec 18 23:40:15 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:15 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::play index undefined Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:40:15 volumio volumio[835]: info: CorePlayQueue::getTrack 33 Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::startPlaybackTimer Dec 18 23:40:15 volumio volumio[835]: info: CorePlayQueue::getTrack 33 Dec 18 23:40:15 volumio volumio[835]: info: [1766097615074] ControllerTidal::clearAddPlayTrack Dec 18 23:40:15 volumio volumio[835]: info: Getting stream with soundQuality LOSSLESS Dec 18 23:40:15 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::updateTrackBlock Dec 18 23:40:15 volumio volumio[835]: info: CorePlayQueue::getTrackBlock Dec 18 23:40:15 volumio volumio[835]: info: sendMpdCommand status took 4 milliseconds Dec 18 23:40:15 volumio volumio[835]: info: sendMpdCommand stop took 1 milliseconds Dec 18 23:40:15 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:15 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:15 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 23:40:15 volumio volumio[835]: info: Dec 18 23:40:15 volumio volumio[835]: ---------------------------- MPD announces system playlist update Dec 18 23:40:15 volumio volumio[835]: info: Ignoring MPD Status Update Dec 18 23:40:15 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:40:15 volumio volumio[835]: info: sendMpdCommand clear took 2 milliseconds Dec 18 23:40:15 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:15 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/466598545&soundQuality=LOSSLESS" Dec 18 23:40:15 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:40:15 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:15 volumio volumio[835]: info: CorePlayQueue::getTrack 33 Dec 18 23:40:15 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:40:15 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:15 volumio volumio[835]: info: CorePlayQueue::getTrack 33 Dec 18 23:40:15 volumio volumio[835]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Dec 18 23:40:15 volumio volumio[835]: info: ------------------------------ 13ms Dec 18 23:40:15 volumio volumio[835]: error: updateQueue error: null Dec 18 23:40:15 volumio volumio[835]: info: Dec 18 23:40:15 volumio volumio[835]: ---------------------------- MPD announces system playlist update Dec 18 23:40:15 volumio volumio[835]: info: Ignoring MPD Status Update Dec 18 23:40:15 volumio volumio[835]: info: ------------------------------ 7ms Dec 18 23:40:15 volumio volumio[835]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/466598545&soundQuality=LOSSLESS" took 6 milliseconds Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:40:15 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand play Dec 18 23:40:15 volumio volumio[835]: STREAMING PROXY: Handling url /?data=tidal://song/466598545&soundQuality=LOSSLESS Dec 18 23:40:15 volumio volumio[835]: info: ------------------------------ 7ms Dec 18 23:40:15 volumio volumio[835]: info: sendMpdCommand play took 7 milliseconds Dec 18 23:40:15 volumio volumio[835]: info: Executing endpoint getStreamUrltidal Dec 18 23:40:15 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Dec 18 23:40:15 volumio volumio[835]: info: getStreamUrl took 398 milliseconds Dec 18 23:40:15 volumio volumio[835]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyMmNiNjhmYTg4YTFjMTM0NjI5NTk4MjZjMjBhODMxNi5tcDQ/0.flac?token=1766101215~MTQwYWEyNTMzNjM3N2YwYmRhZDdhMGNmOThmZDZkMjQwODVlMTBjOQ== Dec 18 23:40:15 volumio volumio[835]: STREAMING PROXY: Response: 200, length: 18639866 Dec 18 23:40:15 volumio volumio[835]: info: Dec 18 23:40:15 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:15 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:15 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:15 volumio volumio[835]: info: Dec 18 23:40:15 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:15 volumio volumio[835]: info: sendMpdCommand status took 2 milliseconds Dec 18 23:40:15 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:15 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:15 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:15 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:15 volumio volumio[835]: info: sendMpdCommand status took 1 milliseconds Dec 18 23:40:15 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:40:15 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:15 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:15 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:15 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:40:15 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:40:15 volumio volumio[835]: info: CorePlayQueue::getTrack 33 Dec 18 23:40:15 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":157,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"466598545&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/466598545&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:40:15 volumio volumio[835]: verbose: CURRENT POSITION 33 Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus stop Dec 18 23:40:15 volumio volumio[835]: info: ------------------------------ 6ms Dec 18 23:40:15 volumio volumio[835]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 18 23:40:15 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:15 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:40:15 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:40:15 volumio volumio[835]: info: CorePlayQueue::getTrack 33 Dec 18 23:40:15 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":157,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"466598545&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/466598545&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:40:15 volumio volumio[835]: verbose: CURRENT POSITION 33 Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Dec 18 23:40:15 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:15 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:40:15 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:15 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:15 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:15 volumio volumio[835]: info: ------------------------------ 20ms Dec 18 23:40:17 volumio volumio[835]: info: CoreCommandRouter::volumioNext Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::next Dec 18 23:40:17 volumio volumio[835]: info: [1766097617468] ControllerTidal::next Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::next Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::stop Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::stPlaybackTimer Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::updateTrackBlock Dec 18 23:40:17 volumio volumio[835]: info: CorePlayQueue::getTrackBlock Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:17 volumio volumio[835]: info: CorePlayQueue::getTrack 33 Dec 18 23:40:17 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:40:17 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::serviceStop Dec 18 23:40:17 volumio volumio[835]: info: CorePlayQueue::getTrack 33 Dec 18 23:40:17 volumio volumio[835]: info: CoreCommandRouter::serviceStop Dec 18 23:40:17 volumio volumio[835]: info: [1766097617471] ControllerTidal::stop Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:40:17 volumio volumio[835]: info: ControllerMpd::stop Dec 18 23:40:17 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 23:40:17 volumio volumio[835]: info: sendMpdCommand stop took 6 milliseconds Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::play index undefined Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:40:17 volumio volumio[835]: info: CorePlayQueue::getTrack 9 Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::startPlaybackTimer Dec 18 23:40:17 volumio volumio[835]: info: CorePlayQueue::getTrack 9 Dec 18 23:40:17 volumio volumio[835]: info: [1766097617480] ControllerTidal::clearAddPlayTrack Dec 18 23:40:17 volumio volumio[835]: info: Getting stream with soundQuality LOSSLESS Dec 18 23:40:17 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::updateTrackBlock Dec 18 23:40:17 volumio volumio[835]: info: CorePlayQueue::getTrackBlock Dec 18 23:40:17 volumio volumio[835]: info: Dec 18 23:40:17 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:17 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:17 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:17 volumio volumio[835]: info: sendMpdCommand stop took 1 milliseconds Dec 18 23:40:17 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 23:40:17 volumio volumio[835]: info: Dec 18 23:40:17 volumio volumio[835]: ---------------------------- MPD announces system playlist update Dec 18 23:40:17 volumio volumio[835]: info: Ignoring MPD Status Update Dec 18 23:40:17 volumio volumio[835]: info: sendMpdCommand status took 2 milliseconds Dec 18 23:40:17 volumio volumio[835]: info: sendMpdCommand clear took 1 milliseconds Dec 18 23:40:17 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:17 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:17 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/386464748&soundQuality=LOSSLESS" Dec 18 23:40:17 volumio volumio[835]: error: updateQueue error: null Dec 18 23:40:17 volumio volumio[835]: info: Dec 18 23:40:17 volumio volumio[835]: ---------------------------- MPD announces system playlist update Dec 18 23:40:17 volumio volumio[835]: STREAMING PROXY: Client dropped request, destroying Dec 18 23:40:17 volumio volumio[835]: info: Ignoring MPD Status Update Dec 18 23:40:17 volumio volumio[835]: info: ------------------------------ 2ms Dec 18 23:40:17 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:40:17 volumio volumio[835]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/386464748&soundQuality=LOSSLESS" took 2 milliseconds Dec 18 23:40:17 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:40:17 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand play Dec 18 23:40:17 volumio volumio[835]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 18 23:40:17 volumio volumio[835]: info: ------------------------------ 6ms Dec 18 23:40:17 volumio volumio[835]: info: ------------------------------ 3ms Dec 18 23:40:17 volumio volumio[835]: info: sendMpdCommand play took 2 milliseconds Dec 18 23:40:17 volumio volumio[835]: STREAMING PROXY: Handling url /?data=tidal://song/386464748&soundQuality=LOSSLESS Dec 18 23:40:17 volumio volumio[835]: info: Executing endpoint getStreamUrltidal Dec 18 23:40:17 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Dec 18 23:40:17 volumio volumio[835]: info: getStreamUrl took 251 milliseconds Dec 18 23:40:17 volumio volumio[835]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyYWExZjZmNzRiZTM3NTM4NmQ2MTBlYTZhMDI5NjJjMC5tcDQ/0.flac?token=1766101217~MDczOTJhZTUwMGYzOTU2MzIxN2ZkNTNhOTUyNWViNzQ1ZmViNTg3Yw== Dec 18 23:40:17 volumio volumio[835]: STREAMING PROXY: Response: 200, length: 20804615 Dec 18 23:40:17 volumio volumio[835]: info: Dec 18 23:40:17 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:17 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:17 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:17 volumio volumio[835]: info: Dec 18 23:40:17 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:17 volumio volumio[835]: info: sendMpdCommand status took 1 milliseconds Dec 18 23:40:17 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:17 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:17 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:17 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:17 volumio volumio[835]: info: sendMpdCommand status took 0 milliseconds Dec 18 23:40:17 volumio volumio[835]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 18 23:40:17 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:17 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:17 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:17 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:40:17 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:40:17 volumio volumio[835]: info: CorePlayQueue::getTrack 9 Dec 18 23:40:17 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":181,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"386464748&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/386464748&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:40:17 volumio volumio[835]: verbose: CURRENT POSITION 9 Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus stop Dec 18 23:40:17 volumio volumio[835]: info: ------------------------------ 5ms Dec 18 23:40:17 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:40:17 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:17 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:40:17 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:40:17 volumio volumio[835]: info: CorePlayQueue::getTrack 9 Dec 18 23:40:17 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":181,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"386464748&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/386464748&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:40:17 volumio volumio[835]: verbose: CURRENT POSITION 9 Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Dec 18 23:40:17 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:17 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:40:17 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:17 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:17 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:17 volumio volumio[835]: info: ------------------------------ 11ms Dec 18 23:40:19 volumio volumio[835]: info: CoreCommandRouter::volumioPrevious Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::previous Dec 18 23:40:19 volumio volumio[835]: info: [1766097619026] ControllerTidal::seek Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:40:19 volumio volumio[835]: info: ControllerMpd::seek Dec 18 23:40:19 volumio volumio[835]: error: null Dec 18 23:40:19 volumio volumio[835]: info: Dec 18 23:40:19 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:19 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:19 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:19 volumio volumio[835]: info: sendMpdCommand status took 1 milliseconds Dec 18 23:40:19 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:19 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:19 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:40:19 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:19 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:40:19 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:40:19 volumio volumio[835]: info: CorePlayQueue::getTrack 9 Dec 18 23:40:19 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":181,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"801 Kbps","isStreaming":false,"title":"386464748&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/386464748&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:40:19 volumio volumio[835]: verbose: CURRENT POSITION 9 Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Dec 18 23:40:19 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:19 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:40:19 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:19 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:19 volumio volumio[835]: info: ------------------------------ 10ms Dec 18 23:40:19 volumio volumio[835]: info: CoreCommandRouter::volumioPrevious Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::previous Dec 18 23:40:19 volumio volumio[835]: info: [1766097619549] ControllerTidal::previous Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::previous Dec 18 23:40:19 volumio volumio[835]: info: CorePlayQueue::getTrack 9 Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::stop Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::stPlaybackTimer Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::updateTrackBlock Dec 18 23:40:19 volumio volumio[835]: info: CorePlayQueue::getTrackBlock Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:19 volumio volumio[835]: info: CorePlayQueue::getTrack 9 Dec 18 23:40:19 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:40:19 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::serviceStop Dec 18 23:40:19 volumio volumio[835]: info: CorePlayQueue::getTrack 9 Dec 18 23:40:19 volumio volumio[835]: info: CoreCommandRouter::serviceStop Dec 18 23:40:19 volumio volumio[835]: info: [1766097619553] ControllerTidal::stop Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:40:19 volumio volumio[835]: info: ControllerMpd::stop Dec 18 23:40:19 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 23:40:19 volumio volumio[835]: STREAMING PROXY: Client dropped request, destroying Dec 18 23:40:19 volumio volumio[835]: info: Dec 18 23:40:19 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:19 volumio volumio[835]: info: sendMpdCommand stop took 6 milliseconds Dec 18 23:40:19 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:19 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:19 volumio volumio[835]: info: sendMpdCommand status took 1 milliseconds Dec 18 23:40:19 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:19 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:19 volumio volumio[835]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 18 23:40:19 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:19 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:40:19 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:19 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:40:19 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:19 volumio volumio[835]: info: CorePlayQueue::getTrack 9 Dec 18 23:40:19 volumio volumio[835]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"386464748&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/386464748&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:40:19 volumio volumio[835]: verbose: CURRENT POSITION 9 Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::syncState stateService stop Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus stop Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:19 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:19 volumio volumio[835]: info: No code Dec 18 23:40:19 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:19 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:19 volumio volumio[835]: info: ------------------------------ 13ms Dec 18 23:40:20 volumio volumio[835]: info: CoreStateMachine::play index undefined Dec 18 23:40:20 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:40:20 volumio volumio[835]: info: CorePlayQueue::getTrack 13 Dec 18 23:40:20 volumio volumio[835]: info: CoreStateMachine::startPlaybackTimer Dec 18 23:40:20 volumio volumio[835]: info: CorePlayQueue::getTrack 13 Dec 18 23:40:20 volumio volumio[835]: info: [1766097620054] ControllerTidal::clearAddPlayTrack Dec 18 23:40:20 volumio volumio[835]: info: Getting stream with soundQuality LOSSLESS Dec 18 23:40:20 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 23:40:20 volumio volumio[835]: info: sendMpdCommand stop took 0 milliseconds Dec 18 23:40:20 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 23:40:20 volumio volumio[835]: info: Dec 18 23:40:20 volumio volumio[835]: ---------------------------- MPD announces system playlist update Dec 18 23:40:20 volumio volumio[835]: info: Ignoring MPD Status Update Dec 18 23:40:20 volumio volumio[835]: info: sendMpdCommand clear took 1 milliseconds Dec 18 23:40:20 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/247902423&soundQuality=LOSSLESS" Dec 18 23:40:20 volumio volumio[835]: error: updateQueue error: null Dec 18 23:40:20 volumio volumio[835]: info: Dec 18 23:40:20 volumio volumio[835]: ---------------------------- MPD announces system playlist update Dec 18 23:40:20 volumio volumio[835]: info: Ignoring MPD Status Update Dec 18 23:40:20 volumio volumio[835]: info: ------------------------------ 1ms Dec 18 23:40:20 volumio volumio[835]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/247902423&soundQuality=LOSSLESS" took 1 milliseconds Dec 18 23:40:20 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:40:20 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand play Dec 18 23:40:20 volumio volumio[835]: info: ------------------------------ 1ms Dec 18 23:40:20 volumio volumio[835]: info: sendMpdCommand play took 1 milliseconds Dec 18 23:40:20 volumio volumio[835]: STREAMING PROXY: Handling url /?data=tidal://song/247902423&soundQuality=LOSSLESS Dec 18 23:40:20 volumio volumio[835]: info: Executing endpoint getStreamUrltidal Dec 18 23:40:20 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Dec 18 23:40:20 volumio volumio[835]: info: getStreamUrl took 412 milliseconds Dec 18 23:40:20 volumio volumio[835]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MTJkZDZlODllNTM5MzBmMGQwNGE2ZTVjMWVhZDhkNV82MS5tcDQ/0.flac?token=1766101220~ZTlkZjkyZGY2ZGRhNTkwZjM4ZDljMGY4YzcwZTE5ZjY5OTY5Y2NlMQ== Dec 18 23:40:20 volumio volumio[835]: STREAMING PROXY: Response: 200, length: 13051388 Dec 18 23:40:20 volumio volumio[835]: info: Dec 18 23:40:20 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:20 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:20 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:20 volumio volumio[835]: info: Dec 18 23:40:20 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:20 volumio volumio[835]: info: sendMpdCommand status took 3 milliseconds Dec 18 23:40:20 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:20 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:20 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:20 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:20 volumio volumio[835]: info: sendMpdCommand status took 1 milliseconds Dec 18 23:40:20 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:40:20 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:20 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:20 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:20 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:40:20 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:40:20 volumio volumio[835]: info: CorePlayQueue::getTrack 13 Dec 18 23:40:20 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":159,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"247902423&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/247902423&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:40:20 volumio volumio[835]: verbose: CURRENT POSITION 13 Dec 18 23:40:20 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:40:20 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus stop Dec 18 23:40:20 volumio volumio[835]: info: ------------------------------ 8ms Dec 18 23:40:20 volumio volumio[835]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 18 23:40:20 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:20 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:40:20 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:40:20 volumio volumio[835]: info: CorePlayQueue::getTrack 13 Dec 18 23:40:20 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":159,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"247902423&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/247902423&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:40:20 volumio volumio[835]: verbose: CURRENT POSITION 13 Dec 18 23:40:20 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:40:20 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Dec 18 23:40:20 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Dec 18 23:40:20 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:20 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:40:20 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:20 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:20 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:20 volumio volumio[835]: info: ------------------------------ 12ms Dec 18 23:40:22 volumio volumio[835]: info: CoreCommandRouter::volumioPrevious Dec 18 23:40:22 volumio volumio[835]: info: CoreStateMachine::previous Dec 18 23:40:22 volumio volumio[835]: info: [1766097622027] ControllerTidal::previous Dec 18 23:40:22 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:40:22 volumio volumio[835]: info: CoreStateMachine::previous Dec 18 23:40:22 volumio volumio[835]: info: CorePlayQueue::getTrack 13 Dec 18 23:40:22 volumio volumio[835]: info: CoreStateMachine::stop Dec 18 23:40:22 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:40:22 volumio volumio[835]: info: CoreStateMachine::stPlaybackTimer Dec 18 23:40:22 volumio volumio[835]: info: CoreStateMachine::updateTrackBlock Dec 18 23:40:22 volumio volumio[835]: info: CorePlayQueue::getTrackBlock Dec 18 23:40:22 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:22 volumio volumio[835]: info: CorePlayQueue::getTrack 13 Dec 18 23:40:22 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:40:22 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:22 volumio volumio[835]: info: CoreStateMachine::serviceStop Dec 18 23:40:22 volumio volumio[835]: info: CorePlayQueue::getTrack 13 Dec 18 23:40:22 volumio volumio[835]: info: CoreCommandRouter::serviceStop Dec 18 23:40:22 volumio volumio[835]: info: [1766097622032] ControllerTidal::stop Dec 18 23:40:22 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:40:22 volumio volumio[835]: info: ControllerMpd::stop Dec 18 23:40:22 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 23:40:22 volumio volumio[835]: STREAMING PROXY: Client dropped request, destroying Dec 18 23:40:22 volumio volumio[835]: info: Dec 18 23:40:22 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:22 volumio volumio[835]: info: sendMpdCommand stop took 5 milliseconds Dec 18 23:40:22 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:22 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:22 volumio volumio[835]: info: sendMpdCommand status took 0 milliseconds Dec 18 23:40:22 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:22 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:22 volumio volumio[835]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 18 23:40:22 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:22 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:40:22 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:40:22 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:22 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:40:22 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:22 volumio volumio[835]: info: CorePlayQueue::getTrack 13 Dec 18 23:40:22 volumio volumio[835]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"247902423&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/247902423&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:40:22 volumio volumio[835]: verbose: CURRENT POSITION 13 Dec 18 23:40:22 volumio volumio[835]: info: CoreStateMachine::syncState stateService stop Dec 18 23:40:22 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus stop Dec 18 23:40:22 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:22 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:22 volumio volumio[835]: info: No code Dec 18 23:40:22 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:22 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:22 volumio volumio[835]: info: ------------------------------ 12ms Dec 18 23:40:22 volumio volumio[835]: info: CoreStateMachine::play index undefined Dec 18 23:40:22 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:40:22 volumio volumio[835]: info: CorePlayQueue::getTrack 6 Dec 18 23:40:22 volumio volumio[835]: info: CoreStateMachine::startPlaybackTimer Dec 18 23:40:22 volumio volumio[835]: info: CorePlayQueue::getTrack 6 Dec 18 23:40:22 volumio volumio[835]: info: [1766097622534] ControllerTidal::clearAddPlayTrack Dec 18 23:40:22 volumio volumio[835]: info: Getting stream with soundQuality LOSSLESS Dec 18 23:40:22 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 23:40:22 volumio volumio[835]: info: sendMpdCommand stop took 1 milliseconds Dec 18 23:40:22 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 23:40:22 volumio volumio[835]: info: Dec 18 23:40:22 volumio volumio[835]: ---------------------------- MPD announces system playlist update Dec 18 23:40:22 volumio volumio[835]: info: Ignoring MPD Status Update Dec 18 23:40:22 volumio volumio[835]: info: sendMpdCommand clear took 1 milliseconds Dec 18 23:40:22 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/453669988&soundQuality=LOSSLESS" Dec 18 23:40:22 volumio volumio[835]: error: updateQueue error: null Dec 18 23:40:22 volumio volumio[835]: info: Dec 18 23:40:22 volumio volumio[835]: ---------------------------- MPD announces system playlist update Dec 18 23:40:22 volumio volumio[835]: info: Ignoring MPD Status Update Dec 18 23:40:22 volumio volumio[835]: info: ------------------------------ 2ms Dec 18 23:40:22 volumio volumio[835]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/453669988&soundQuality=LOSSLESS" took 1 milliseconds Dec 18 23:40:22 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:40:22 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand play Dec 18 23:40:22 volumio volumio[835]: info: ------------------------------ 2ms Dec 18 23:40:22 volumio volumio[835]: info: sendMpdCommand play took 1 milliseconds Dec 18 23:40:22 volumio volumio[835]: STREAMING PROXY: Handling url /?data=tidal://song/453669988&soundQuality=LOSSLESS Dec 18 23:40:22 volumio volumio[835]: info: Executing endpoint getStreamUrltidal Dec 18 23:40:22 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Dec 18 23:40:22 volumio volumio[835]: info: getStreamUrl took 440 milliseconds Dec 18 23:40:22 volumio volumio[835]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQzNWY4MjU3ZmQwYzE2ZWRlN2NkYTdkMzM3ODkxYzRmOS5tcDQ/0.flac?token=1766101222~NDcxMTViNjcxZmQ2M2Q3ZGU3NzdlYjgxNzJiZDE2YWIwMzRkOTVlZg== Dec 18 23:40:23 volumio volumio[835]: STREAMING PROXY: Response: 200, length: 23430184 Dec 18 23:40:23 volumio volumio[835]: info: Dec 18 23:40:23 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:23 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:23 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:23 volumio volumio[835]: info: sendMpdCommand status took 2 milliseconds Dec 18 23:40:23 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:23 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:23 volumio volumio[835]: info: Dec 18 23:40:23 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:23 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:23 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:23 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:40:23 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:23 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:40:23 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:40:23 volumio volumio[835]: info: CorePlayQueue::getTrack 6 Dec 18 23:40:23 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"453669988&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/453669988&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:40:23 volumio volumio[835]: verbose: CURRENT POSITION 6 Dec 18 23:40:23 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:40:23 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus stop Dec 18 23:40:23 volumio volumio[835]: info: ------------------------------ 5ms Dec 18 23:40:23 volumio volumio[835]: info: sendMpdCommand status took 1 milliseconds Dec 18 23:40:23 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:23 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:23 volumio volumio[835]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 18 23:40:23 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:23 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:40:23 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:40:23 volumio volumio[835]: info: CorePlayQueue::getTrack 6 Dec 18 23:40:23 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"453669988&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/453669988&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:40:23 volumio volumio[835]: verbose: CURRENT POSITION 6 Dec 18 23:40:23 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:40:23 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Dec 18 23:40:23 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Dec 18 23:40:23 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:23 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:40:23 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:23 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:23 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:23 volumio volumio[835]: info: ------------------------------ 8ms Dec 18 23:40:26 volumio volumio[835]: STREAMING PROXY: Client dropped request, destroying Dec 18 23:40:30 volumio volumio[835]: info: CoreCommandRouter::volumioPlay Dec 18 23:40:30 volumio volumio[835]: info: CoreStateMachine::play index 4 Dec 18 23:40:30 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:40:30 volumio volumio[835]: info: CoreStateMachine::stop Dec 18 23:40:30 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:40:30 volumio volumio[835]: info: CoreStateMachine::stPlaybackTimer Dec 18 23:40:30 volumio volumio[835]: info: CoreStateMachine::updateTrackBlock Dec 18 23:40:30 volumio volumio[835]: info: CorePlayQueue::getTrackBlock Dec 18 23:40:30 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:30 volumio volumio[835]: info: CorePlayQueue::getTrack 6 Dec 18 23:40:30 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:40:30 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:30 volumio volumio[835]: info: CoreStateMachine::serviceStop Dec 18 23:40:30 volumio volumio[835]: info: CorePlayQueue::getTrack 6 Dec 18 23:40:30 volumio volumio[835]: info: CoreCommandRouter::serviceStop Dec 18 23:40:30 volumio volumio[835]: info: [1766097630414] ControllerTidal::stop Dec 18 23:40:30 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:40:30 volumio volumio[835]: info: ControllerMpd::stop Dec 18 23:40:30 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 23:40:30 volumio volumio[835]: info: Dec 18 23:40:30 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:30 volumio volumio[835]: info: sendMpdCommand stop took 6 milliseconds Dec 18 23:40:30 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:30 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:30 volumio volumio[835]: info: CoreStateMachine::play index undefined Dec 18 23:40:30 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:40:30 volumio volumio[835]: info: CorePlayQueue::getTrack 4 Dec 18 23:40:30 volumio volumio[835]: info: CoreStateMachine::startPlaybackTimer Dec 18 23:40:30 volumio volumio[835]: info: CorePlayQueue::getTrack 4 Dec 18 23:40:30 volumio volumio[835]: info: [1766097630421] ControllerTidal::clearAddPlayTrack Dec 18 23:40:30 volumio volumio[835]: info: Getting stream with soundQuality LOSSLESS Dec 18 23:40:30 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 23:40:30 volumio volumio[835]: info: sendMpdCommand status took 1 milliseconds Dec 18 23:40:30 volumio volumio[835]: info: sendMpdCommand stop took 1 milliseconds Dec 18 23:40:30 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:30 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:30 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 23:40:30 volumio volumio[835]: info: Dec 18 23:40:30 volumio volumio[835]: ---------------------------- MPD announces system playlist update Dec 18 23:40:30 volumio volumio[835]: info: Ignoring MPD Status Update Dec 18 23:40:30 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:40:30 volumio volumio[835]: info: sendMpdCommand clear took 1 milliseconds Dec 18 23:40:30 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:30 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/345250732&soundQuality=LOSSLESS" Dec 18 23:40:30 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:40:30 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:40:30 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:30 volumio volumio[835]: info: CorePlayQueue::getTrack 4 Dec 18 23:40:30 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:40:30 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:30 volumio volumio[835]: info: CorePlayQueue::getTrack 4 Dec 18 23:40:30 volumio volumio[835]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Dec 18 23:40:30 volumio volumio[835]: info: ------------------------------ 8ms Dec 18 23:40:30 volumio volumio[835]: error: updateQueue error: null Dec 18 23:40:30 volumio volumio[835]: info: Dec 18 23:40:30 volumio volumio[835]: ---------------------------- MPD announces system playlist update Dec 18 23:40:30 volumio volumio[835]: info: Ignoring MPD Status Update Dec 18 23:40:30 volumio volumio[835]: info: ------------------------------ 6ms Dec 18 23:40:30 volumio volumio[835]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/345250732&soundQuality=LOSSLESS" took 5 milliseconds Dec 18 23:40:30 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:40:30 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand play Dec 18 23:40:30 volumio volumio[835]: STREAMING PROXY: Handling url /?data=tidal://song/345250732&soundQuality=LOSSLESS Dec 18 23:40:30 volumio volumio[835]: info: ------------------------------ 7ms Dec 18 23:40:30 volumio volumio[835]: info: sendMpdCommand play took 7 milliseconds Dec 18 23:40:30 volumio volumio[835]: info: Executing endpoint getStreamUrltidal Dec 18 23:40:30 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Dec 18 23:40:30 volumio volumio[835]: info: getStreamUrl took 234 milliseconds Dec 18 23:40:30 volumio volumio[835]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ0Y2Q5MGM5ODgwYzZiNzg1Njk2ZDE3MWRmYzY0NTRiZC5tcDQ/0.flac?token=1766101230~YzUxYzBkMmQzMThhOTk1ZDVmZTE1ODllMjZjMjdjZDQ5MDNjYmNlYQ== Dec 18 23:40:30 volumio volumio[835]: STREAMING PROXY: Response: 200, length: 15357222 Dec 18 23:40:30 volumio volumio[835]: info: Dec 18 23:40:30 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:30 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:30 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:30 volumio volumio[835]: info: Dec 18 23:40:30 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:30 volumio volumio[835]: info: sendMpdCommand status took 2 milliseconds Dec 18 23:40:30 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:30 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:30 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:30 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:30 volumio volumio[835]: info: sendMpdCommand status took 1 milliseconds Dec 18 23:40:30 volumio volumio[835]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 18 23:40:30 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:30 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:30 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:30 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:40:30 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:40:30 volumio volumio[835]: info: CorePlayQueue::getTrack 4 Dec 18 23:40:30 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"345250732&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/345250732&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:40:30 volumio volumio[835]: verbose: CURRENT POSITION 4 Dec 18 23:40:30 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:40:30 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus stop Dec 18 23:40:30 volumio volumio[835]: info: ------------------------------ 7ms Dec 18 23:40:30 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:40:30 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:30 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:40:30 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:40:30 volumio volumio[835]: info: CorePlayQueue::getTrack 4 Dec 18 23:40:30 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"345250732&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/345250732&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:40:30 volumio volumio[835]: verbose: CURRENT POSITION 4 Dec 18 23:40:30 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:40:30 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Dec 18 23:40:30 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Dec 18 23:40:30 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:30 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:40:30 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:30 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:30 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:30 volumio volumio[835]: info: ------------------------------ 10ms Dec 18 23:40:33 volumio volumio[835]: STREAMING PROXY: Client dropped request, destroying Dec 18 23:40:38 volumio volumio[835]: info: CoreCommandRouter::volumioPause Dec 18 23:40:38 volumio volumio[835]: info: CoreStateMachine::pause Dec 18 23:40:38 volumio volumio[835]: info: CoreStateMachine::stPlaybackTimer Dec 18 23:40:38 volumio volumio[835]: info: CoreStateMachine::servicePause Dec 18 23:40:38 volumio volumio[835]: info: CorePlayQueue::getTrack 4 Dec 18 23:40:38 volumio volumio[835]: info: CoreCommandRouter::servicePause Dec 18 23:40:38 volumio volumio[835]: info: [1766097638418] ControllerTidal::pause Dec 18 23:40:38 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:40:38 volumio volumio[835]: info: ControllerMpd::pause Dec 18 23:40:38 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand pause Dec 18 23:40:38 volumio volumio[835]: info: Dec 18 23:40:38 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:40:38 volumio volumio[835]: info: sendMpdCommand pause took 1 milliseconds Dec 18 23:40:38 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:40:38 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:40:38 volumio volumio[835]: info: sendMpdCommand status took 0 milliseconds Dec 18 23:40:38 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:40:38 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:40:38 volumio volumio[835]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 18 23:40:38 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:40:38 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:40:38 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:40:38 volumio volumio[835]: info: CorePlayQueue::getTrack 4 Dec 18 23:40:38 volumio volumio[835]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":8213,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"725 Kbps","isStreaming":false,"title":"345250732&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/345250732&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:40:38 volumio volumio[835]: verbose: CURRENT POSITION 4 Dec 18 23:40:38 volumio volumio[835]: info: CoreStateMachine::syncState stateService pause Dec 18 23:40:38 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus pause Dec 18 23:40:38 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:40:38 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:40:38 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:40:38 volumio volumio[835]: info: CoreStateMachine::stPlaybackTimer Dec 18 23:40:38 volumio volumio[835]: info: ------------------------------ 6ms Dec 18 23:40:43 volumio volumio[835]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 23:40:43 volumio volumio[835]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 23:40:43 volumio volumio[835]: info: Retrieving Cloud Streaming UI Dec 18 23:40:43 volumio volumio[835]: info: Getting Tidal Cloud Configuration Dec 18 23:40:43 volumio volumio[835]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 23:40:43 volumio volumio[835]: info: Getting Qobuz Cloud Configuration Dec 18 23:40:43 volumio volumio[835]: info: Asking plugin for UI Config Dec 18 23:40:43 volumio volumio[835]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 23:40:43 volumio volumio[835]: info: Getting Spotify Cloud Configuration Dec 18 23:40:43 volumio volumio[835]: info: Asking plugin for UI Config Dec 18 23:40:43 volumio volumio[835]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 23:40:43 volumio volumio[835]: info: Saving Spotify Acccount Dec 18 23:40:43 volumio volumio[835]: info: Got it Dec 18 23:40:43 volumio volumio[835]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 18 23:40:43 volumio volumio[835]: info: Got Tidal Cloud Configuration Dec 18 23:40:43 volumio volumio[835]: info: Got it Dec 18 23:40:43 volumio volumio[835]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 23:40:43 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 18 23:40:43 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 23:40:43 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 23:40:43 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 23:40:43 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 23:40:43 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 23:40:43 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 23:40:43 volumio volumio[835]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 23:40:43 volumio volumio[835]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 23:40:43 volumio volumio[835]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 23:40:43 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 23:40:43 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 18 23:40:47 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 23:40:51 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 23:40:55 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 23:40:59 volumio volumio[835]: info: CALLMETHOD: music_service tidal saveConfig [object Object] Dec 18 23:40:59 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: tidal , saveConfig Dec 18 23:40:59 volumio volumio[835]: info: Saving configuration Dec 18 23:40:59 volumio volumio[835]: info: Done Dec 18 23:40:59 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 23:41:03 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 23:41:04 volumio volumio[835]: info: CALLMETHOD: music_service tidal saveConfig [object Object] Dec 18 23:41:04 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: tidal , saveConfig Dec 18 23:41:04 volumio volumio[835]: info: Saving configuration Dec 18 23:41:04 volumio volumio[835]: info: Done Dec 18 23:41:07 volumio volumio[835]: info: CoreCommandRouter::volumioPrevious Dec 18 23:41:07 volumio volumio[835]: info: CoreStateMachine::previous Dec 18 23:41:07 volumio volumio[835]: info: CoreStateMachine::updateTrackBlock Dec 18 23:41:07 volumio volumio[835]: info: CorePlayQueue::getTrackBlock Dec 18 23:41:07 volumio volumio[835]: info: CoreStateMachine::serviceClearAddPlay Dec 18 23:41:07 volumio volumio[835]: info: CoreCommandRouter::serviceClearAddPlayTracks Dec 18 23:41:07 volumio volumio[835]: error: WARNING: No clearAddPlayTracks method for service tidal Dec 18 23:41:09 volumio volumio[835]: info: CoreCommandRouter::volumioPlay Dec 18 23:41:09 volumio volumio[835]: info: CoreStateMachine::play index undefined Dec 18 23:41:09 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:41:09 volumio volumio[835]: info: CorePlayQueue::getTrack 52 Dec 18 23:41:09 volumio volumio[835]: info: CoreStateMachine::startPlaybackTimer Dec 18 23:41:09 volumio volumio[835]: info: CorePlayQueue::getTrack 52 Dec 18 23:41:09 volumio volumio[835]: info: [1766097669239] ControllerTidal::resume Dec 18 23:41:09 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:41:09 volumio volumio[835]: info: ControllerMpd::resume Dec 18 23:41:09 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand play Dec 18 23:41:09 volumio volumio[835]: info: Dec 18 23:41:09 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:41:09 volumio volumio[835]: info: sendMpdCommand play took 6 milliseconds Dec 18 23:41:09 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:41:09 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:41:09 volumio volumio[835]: info: sendMpdCommand status took 1 milliseconds Dec 18 23:41:09 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:41:09 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:41:09 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:41:09 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:41:09 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:41:09 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:41:09 volumio volumio[835]: info: CorePlayQueue::getTrack 52 Dec 18 23:41:09 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9083,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"653 Kbps","isStreaming":false,"title":"345250732&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/345250732&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:41:09 volumio volumio[835]: verbose: CURRENT POSITION 52 Dec 18 23:41:09 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:41:09 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus pause Dec 18 23:41:09 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:09 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:41:09 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:09 volumio volumio[835]: info: ------------------------------ 7ms Dec 18 23:41:10 volumio volumio[835]: info: CoreCommandRouter::volumioPrevious Dec 18 23:41:10 volumio volumio[835]: info: CoreStateMachine::previous Dec 18 23:41:10 volumio volumio[835]: info: ControllerMpd::seek Dec 18 23:41:10 volumio volumio[835]: error: null Dec 18 23:41:10 volumio volumio[835]: info: Dec 18 23:41:10 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:41:10 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:41:10 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:41:10 volumio volumio[835]: info: sendMpdCommand status took 1 milliseconds Dec 18 23:41:10 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:41:10 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:41:10 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:41:10 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:41:10 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:41:10 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:41:10 volumio volumio[835]: info: CorePlayQueue::getTrack 52 Dec 18 23:41:10 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"688 Kbps","isStreaming":false,"title":"345250732&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/345250732&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:41:10 volumio volumio[835]: verbose: CURRENT POSITION 52 Dec 18 23:41:10 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:41:10 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Dec 18 23:41:10 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Dec 18 23:41:10 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:10 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:41:10 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:10 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:10 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:10 volumio volumio[835]: info: ------------------------------ 10ms Dec 18 23:41:12 volumio volumio[835]: info: CoreCommandRouter::volumioPrevious Dec 18 23:41:12 volumio volumio[835]: info: CoreStateMachine::previous Dec 18 23:41:12 volumio volumio[835]: info: [1766097672154] ControllerTidal::previous Dec 18 23:41:12 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:41:12 volumio volumio[835]: info: CoreStateMachine::previous Dec 18 23:41:12 volumio volumio[835]: info: CorePlayQueue::getTrack 52 Dec 18 23:41:12 volumio volumio[835]: info: CoreStateMachine::stop Dec 18 23:41:12 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:41:12 volumio volumio[835]: info: CoreStateMachine::stPlaybackTimer Dec 18 23:41:12 volumio volumio[835]: info: CoreStateMachine::updateTrackBlock Dec 18 23:41:12 volumio volumio[835]: info: CorePlayQueue::getTrackBlock Dec 18 23:41:12 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:12 volumio volumio[835]: info: CorePlayQueue::getTrack 52 Dec 18 23:41:12 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:41:12 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:12 volumio volumio[835]: info: CoreStateMachine::serviceStop Dec 18 23:41:12 volumio volumio[835]: info: CorePlayQueue::getTrack 52 Dec 18 23:41:12 volumio volumio[835]: info: CoreCommandRouter::serviceStop Dec 18 23:41:12 volumio volumio[835]: info: [1766097672159] ControllerTidal::stop Dec 18 23:41:12 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:41:12 volumio volumio[835]: info: ControllerMpd::stop Dec 18 23:41:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 23:41:12 volumio volumio[835]: info: Dec 18 23:41:12 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:41:12 volumio volumio[835]: info: sendMpdCommand stop took 7 milliseconds Dec 18 23:41:12 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:41:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:41:12 volumio volumio[835]: info: sendMpdCommand status took 1 milliseconds Dec 18 23:41:12 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:41:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:41:12 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:41:12 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:41:12 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:41:12 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:41:12 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:12 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:41:12 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:12 volumio volumio[835]: info: CorePlayQueue::getTrack 52 Dec 18 23:41:12 volumio volumio[835]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"345250732&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/345250732&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:41:12 volumio volumio[835]: verbose: CURRENT POSITION 52 Dec 18 23:41:12 volumio volumio[835]: info: CoreStateMachine::syncState stateService stop Dec 18 23:41:12 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus stop Dec 18 23:41:12 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:12 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:12 volumio volumio[835]: info: No code Dec 18 23:41:12 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:12 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:12 volumio volumio[835]: info: ------------------------------ 12ms Dec 18 23:41:12 volumio volumio[835]: info: CoreStateMachine::play index undefined Dec 18 23:41:12 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:41:12 volumio volumio[835]: info: CorePlayQueue::getTrack 71 Dec 18 23:41:12 volumio volumio[835]: info: CoreStateMachine::startPlaybackTimer Dec 18 23:41:12 volumio volumio[835]: info: CorePlayQueue::getTrack 71 Dec 18 23:41:12 volumio volumio[835]: info: [1766097672660] ControllerTidal::clearAddPlayTrack Dec 18 23:41:12 volumio volumio[835]: info: Getting stream with soundQuality LOSSLESS Dec 18 23:41:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 23:41:12 volumio volumio[835]: info: sendMpdCommand stop took 1 milliseconds Dec 18 23:41:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 23:41:12 volumio volumio[835]: info: Dec 18 23:41:12 volumio volumio[835]: ---------------------------- MPD announces system playlist update Dec 18 23:41:12 volumio volumio[835]: info: Ignoring MPD Status Update Dec 18 23:41:12 volumio volumio[835]: info: sendMpdCommand clear took 2 milliseconds Dec 18 23:41:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/442162207&soundQuality=LOSSLESS" Dec 18 23:41:12 volumio volumio[835]: error: updateQueue error: null Dec 18 23:41:12 volumio volumio[835]: info: Dec 18 23:41:12 volumio volumio[835]: ---------------------------- MPD announces system playlist update Dec 18 23:41:12 volumio volumio[835]: info: Ignoring MPD Status Update Dec 18 23:41:12 volumio volumio[835]: info: ------------------------------ 3ms Dec 18 23:41:12 volumio volumio[835]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/442162207&soundQuality=LOSSLESS" took 2 milliseconds Dec 18 23:41:12 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:41:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand play Dec 18 23:41:12 volumio volumio[835]: STREAMING PROXY: Handling url /?data=tidal://song/442162207&soundQuality=LOSSLESS Dec 18 23:41:12 volumio volumio[835]: info: ------------------------------ 7ms Dec 18 23:41:12 volumio volumio[835]: info: sendMpdCommand play took 6 milliseconds Dec 18 23:41:12 volumio volumio[835]: info: Executing endpoint getStreamUrltidal Dec 18 23:41:12 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Dec 18 23:41:13 volumio volumio[835]: info: getStreamUrl took 374 milliseconds Dec 18 23:41:13 volumio volumio[835]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ2NDNmMmU0NjlkMzhiYzRlNWY5NDYzY2M1MWRlNzNhYi5tcDQ/0.flac?token=1766101272~NTE2NWM1ODUwOWE3ODdlYzZiNjk0YTlkOWM3NjhjZGVmYTAzMTIyZg== Dec 18 23:41:13 volumio volumio[835]: STREAMING PROXY: Response: 200, length: 16866177 Dec 18 23:41:13 volumio volumio[835]: info: Dec 18 23:41:13 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:41:13 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:41:13 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:41:13 volumio volumio[835]: info: Dec 18 23:41:13 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:41:13 volumio volumio[835]: info: sendMpdCommand status took 6 milliseconds Dec 18 23:41:13 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:41:13 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:41:13 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:41:13 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:41:13 volumio volumio[835]: info: sendMpdCommand status took 1 milliseconds Dec 18 23:41:13 volumio volumio[835]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 18 23:41:13 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:41:13 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:41:13 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:41:13 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:41:13 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:41:13 volumio volumio[835]: info: CorePlayQueue::getTrack 71 Dec 18 23:41:13 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":136,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"442162207&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/442162207&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:41:13 volumio volumio[835]: verbose: CURRENT POSITION 71 Dec 18 23:41:13 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:41:13 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus stop Dec 18 23:41:13 volumio volumio[835]: info: ------------------------------ 15ms Dec 18 23:41:13 volumio volumio[835]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 18 23:41:13 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:41:13 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:41:13 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:41:13 volumio volumio[835]: info: CorePlayQueue::getTrack 71 Dec 18 23:41:13 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":136,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"442162207&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/442162207&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:41:13 volumio volumio[835]: verbose: CURRENT POSITION 71 Dec 18 23:41:13 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:41:13 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Dec 18 23:41:13 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Dec 18 23:41:13 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:13 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:41:13 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:13 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:13 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:13 volumio volumio[835]: info: ------------------------------ 21ms Dec 18 23:41:15 volumio volumio[835]: STREAMING PROXY: Client dropped request, destroying Dec 18 23:41:16 volumio volumio[835]: info: CoreCommandRouter::volumioNext Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::next Dec 18 23:41:16 volumio volumio[835]: info: [1766097676009] ControllerTidal::next Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::next Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::stop Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::stPlaybackTimer Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::updateTrackBlock Dec 18 23:41:16 volumio volumio[835]: info: CorePlayQueue::getTrackBlock Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:16 volumio volumio[835]: info: CorePlayQueue::getTrack 71 Dec 18 23:41:16 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:41:16 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::serviceStop Dec 18 23:41:16 volumio volumio[835]: info: CorePlayQueue::getTrack 71 Dec 18 23:41:16 volumio volumio[835]: info: CoreCommandRouter::serviceStop Dec 18 23:41:16 volumio volumio[835]: info: [1766097676014] ControllerTidal::stop Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:41:16 volumio volumio[835]: info: ControllerMpd::stop Dec 18 23:41:16 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 23:41:16 volumio volumio[835]: info: Dec 18 23:41:16 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:41:16 volumio volumio[835]: info: sendMpdCommand stop took 6 milliseconds Dec 18 23:41:16 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:41:16 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::play index undefined Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:41:16 volumio volumio[835]: info: CorePlayQueue::getTrack 2 Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::startPlaybackTimer Dec 18 23:41:16 volumio volumio[835]: info: CorePlayQueue::getTrack 2 Dec 18 23:41:16 volumio volumio[835]: info: [1766097676021] ControllerTidal::clearAddPlayTrack Dec 18 23:41:16 volumio volumio[835]: info: Getting stream with soundQuality LOSSLESS Dec 18 23:41:16 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::updateTrackBlock Dec 18 23:41:16 volumio volumio[835]: info: CorePlayQueue::getTrackBlock Dec 18 23:41:16 volumio volumio[835]: info: sendMpdCommand status took 2 milliseconds Dec 18 23:41:16 volumio volumio[835]: info: sendMpdCommand stop took 1 milliseconds Dec 18 23:41:16 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:41:16 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:41:16 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 23:41:16 volumio volumio[835]: info: Dec 18 23:41:16 volumio volumio[835]: ---------------------------- MPD announces system playlist update Dec 18 23:41:16 volumio volumio[835]: info: Ignoring MPD Status Update Dec 18 23:41:16 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:41:16 volumio volumio[835]: info: sendMpdCommand clear took 1 milliseconds Dec 18 23:41:16 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:41:16 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/225704054&soundQuality=LOSSLESS" Dec 18 23:41:16 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:41:16 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:16 volumio volumio[835]: info: CorePlayQueue::getTrack 2 Dec 18 23:41:16 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:41:16 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:16 volumio volumio[835]: info: CorePlayQueue::getTrack 2 Dec 18 23:41:16 volumio volumio[835]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Dec 18 23:41:16 volumio volumio[835]: info: ------------------------------ 8ms Dec 18 23:41:16 volumio volumio[835]: error: updateQueue error: null Dec 18 23:41:16 volumio volumio[835]: info: Dec 18 23:41:16 volumio volumio[835]: ---------------------------- MPD announces system playlist update Dec 18 23:41:16 volumio volumio[835]: info: Ignoring MPD Status Update Dec 18 23:41:16 volumio volumio[835]: info: ------------------------------ 5ms Dec 18 23:41:16 volumio volumio[835]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/225704054&soundQuality=LOSSLESS" took 5 milliseconds Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:41:16 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand play Dec 18 23:41:16 volumio volumio[835]: STREAMING PROXY: Handling url /?data=tidal://song/225704054&soundQuality=LOSSLESS Dec 18 23:41:16 volumio volumio[835]: info: ------------------------------ 4ms Dec 18 23:41:16 volumio volumio[835]: info: sendMpdCommand play took 4 milliseconds Dec 18 23:41:16 volumio volumio[835]: info: Executing endpoint getStreamUrltidal Dec 18 23:41:16 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Dec 18 23:41:16 volumio volumio[835]: info: getStreamUrl took 233 milliseconds Dec 18 23:41:16 volumio volumio[835]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidkMmJjMDAyZTJjODkzYjFkNGY5ODI1ZmNjNjFiZDhkN182MS5tcDQ/0.flac?token=1766101276~ZTM4ZjEwN2E4ZjA0YmYzMmRiMWRlNjFhNzQzMjE4MjE5ZWE5MmZjNg== Dec 18 23:41:16 volumio volumio[835]: STREAMING PROXY: Response: 200, length: 13068666 Dec 18 23:41:16 volumio volumio[835]: info: Dec 18 23:41:16 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:41:16 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:41:16 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:41:16 volumio volumio[835]: info: Dec 18 23:41:16 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:41:16 volumio volumio[835]: info: sendMpdCommand status took 2 milliseconds Dec 18 23:41:16 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:41:16 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:41:16 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:41:16 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:41:16 volumio volumio[835]: info: sendMpdCommand status took 1 milliseconds Dec 18 23:41:16 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:41:16 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:41:16 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:41:16 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:41:16 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:41:16 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:41:16 volumio volumio[835]: info: CorePlayQueue::getTrack 2 Dec 18 23:41:16 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":152,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"225704054&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/225704054&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:41:16 volumio volumio[835]: verbose: CURRENT POSITION 2 Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus stop Dec 18 23:41:16 volumio volumio[835]: info: ------------------------------ 8ms Dec 18 23:41:16 volumio volumio[835]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 18 23:41:16 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:41:16 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:41:16 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:41:16 volumio volumio[835]: info: CorePlayQueue::getTrack 2 Dec 18 23:41:16 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":152,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"225704054&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/225704054&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:41:16 volumio volumio[835]: verbose: CURRENT POSITION 2 Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Dec 18 23:41:16 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:16 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:41:16 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:16 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:16 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:16 volumio volumio[835]: info: ------------------------------ 11ms Dec 18 23:41:18 volumio volumio[835]: STREAMING PROXY: Client dropped request, destroying Dec 18 23:41:18 volumio volumio[835]: info: CoreCommandRouter::volumioNext Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::next Dec 18 23:41:18 volumio volumio[835]: info: [1766097678360] ControllerTidal::next Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::next Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::stop Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::stPlaybackTimer Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::updateTrackBlock Dec 18 23:41:18 volumio volumio[835]: info: CorePlayQueue::getTrackBlock Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:18 volumio volumio[835]: info: CorePlayQueue::getTrack 2 Dec 18 23:41:18 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:41:18 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::serviceStop Dec 18 23:41:18 volumio volumio[835]: info: CorePlayQueue::getTrack 2 Dec 18 23:41:18 volumio volumio[835]: info: CoreCommandRouter::serviceStop Dec 18 23:41:18 volumio volumio[835]: info: [1766097678363] ControllerTidal::stop Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:41:18 volumio volumio[835]: info: ControllerMpd::stop Dec 18 23:41:18 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 23:41:18 volumio volumio[835]: info: Dec 18 23:41:18 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:41:18 volumio volumio[835]: info: sendMpdCommand stop took 6 milliseconds Dec 18 23:41:18 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:41:18 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::play index undefined Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:41:18 volumio volumio[835]: info: CorePlayQueue::getTrack 29 Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::startPlaybackTimer Dec 18 23:41:18 volumio volumio[835]: info: CorePlayQueue::getTrack 29 Dec 18 23:41:18 volumio volumio[835]: info: [1766097678370] ControllerTidal::clearAddPlayTrack Dec 18 23:41:18 volumio volumio[835]: info: Getting stream with soundQuality LOSSLESS Dec 18 23:41:18 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::updateTrackBlock Dec 18 23:41:18 volumio volumio[835]: info: CorePlayQueue::getTrackBlock Dec 18 23:41:18 volumio volumio[835]: info: sendMpdCommand status took 2 milliseconds Dec 18 23:41:18 volumio volumio[835]: info: sendMpdCommand stop took 1 milliseconds Dec 18 23:41:18 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:41:18 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:41:18 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 23:41:18 volumio volumio[835]: info: Dec 18 23:41:18 volumio volumio[835]: ---------------------------- MPD announces system playlist update Dec 18 23:41:18 volumio volumio[835]: info: Ignoring MPD Status Update Dec 18 23:41:18 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:41:18 volumio volumio[835]: info: sendMpdCommand clear took 1 milliseconds Dec 18 23:41:18 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:41:18 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/353510112&soundQuality=LOSSLESS" Dec 18 23:41:18 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:41:18 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:18 volumio volumio[835]: info: CorePlayQueue::getTrack 29 Dec 18 23:41:18 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:41:18 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:18 volumio volumio[835]: info: CorePlayQueue::getTrack 29 Dec 18 23:41:18 volumio volumio[835]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Dec 18 23:41:18 volumio volumio[835]: info: ------------------------------ 7ms Dec 18 23:41:18 volumio volumio[835]: error: updateQueue error: null Dec 18 23:41:18 volumio volumio[835]: info: Dec 18 23:41:18 volumio volumio[835]: ---------------------------- MPD announces system playlist update Dec 18 23:41:18 volumio volumio[835]: info: Ignoring MPD Status Update Dec 18 23:41:18 volumio volumio[835]: info: ------------------------------ 5ms Dec 18 23:41:18 volumio volumio[835]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/353510112&soundQuality=LOSSLESS" took 4 milliseconds Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:41:18 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand play Dec 18 23:41:18 volumio volumio[835]: STREAMING PROXY: Handling url /?data=tidal://song/353510112&soundQuality=LOSSLESS Dec 18 23:41:18 volumio volumio[835]: info: ------------------------------ 4ms Dec 18 23:41:18 volumio volumio[835]: info: sendMpdCommand play took 5 milliseconds Dec 18 23:41:18 volumio volumio[835]: info: Executing endpoint getStreamUrltidal Dec 18 23:41:18 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Dec 18 23:41:18 volumio volumio[835]: info: getStreamUrl took 420 milliseconds Dec 18 23:41:18 volumio volumio[835]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ2OTdlYjExY2M3NTM0NDcwYTU4MjJkYzExN2MxZjJmYS5tcDQ/0.flac?token=1766101278~MjRmYTBkNTdjNWY2NDI3MjFiNWVjN2VmNTUwOGNiZjczYTRmOTk1NA== Dec 18 23:41:18 volumio volumio[835]: STREAMING PROXY: Response: 200, length: 21806953 Dec 18 23:41:18 volumio volumio[835]: info: Dec 18 23:41:18 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:41:18 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:41:18 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:41:18 volumio volumio[835]: info: Dec 18 23:41:18 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:41:18 volumio volumio[835]: info: sendMpdCommand status took 3 milliseconds Dec 18 23:41:18 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:41:18 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:41:18 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:41:18 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:41:18 volumio volumio[835]: info: sendMpdCommand status took 1 milliseconds Dec 18 23:41:18 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:41:18 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:41:18 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:41:18 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:41:18 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:41:18 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:41:18 volumio volumio[835]: info: CorePlayQueue::getTrack 29 Dec 18 23:41:18 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":196,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"353510112&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/353510112&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:41:18 volumio volumio[835]: verbose: CURRENT POSITION 29 Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus stop Dec 18 23:41:18 volumio volumio[835]: info: ------------------------------ 6ms Dec 18 23:41:18 volumio volumio[835]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 18 23:41:18 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:41:18 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:41:18 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:41:18 volumio volumio[835]: info: CorePlayQueue::getTrack 29 Dec 18 23:41:18 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":196,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"353510112&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/353510112&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:41:18 volumio volumio[835]: verbose: CURRENT POSITION 29 Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Dec 18 23:41:18 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:18 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:41:18 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:18 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:18 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:18 volumio volumio[835]: info: ------------------------------ 9ms Dec 18 23:41:20 volumio volumio[835]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 23:41:20 volumio volumio[835]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 23:41:20 volumio volumio[835]: info: Retrieving Cloud Streaming UI Dec 18 23:41:20 volumio volumio[835]: info: Getting Tidal Cloud Configuration Dec 18 23:41:20 volumio volumio[835]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 23:41:20 volumio volumio[835]: info: Getting Qobuz Cloud Configuration Dec 18 23:41:20 volumio volumio[835]: info: Asking plugin for UI Config Dec 18 23:41:20 volumio volumio[835]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 23:41:20 volumio volumio[835]: info: Getting Spotify Cloud Configuration Dec 18 23:41:20 volumio volumio[835]: info: Asking plugin for UI Config Dec 18 23:41:20 volumio volumio[835]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 23:41:20 volumio volumio[835]: info: Saving Spotify Acccount Dec 18 23:41:20 volumio volumio[835]: info: Got it Dec 18 23:41:20 volumio volumio[835]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 18 23:41:20 volumio volumio[835]: info: Got Tidal Cloud Configuration Dec 18 23:41:20 volumio volumio[835]: info: Got it Dec 18 23:41:20 volumio volumio[835]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 23:41:20 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 18 23:41:20 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 23:41:20 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 23:41:20 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 23:41:20 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 23:41:20 volumio volumio[835]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 23:41:20 volumio volumio[835]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 23:41:20 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 23:41:20 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 18 23:41:22 volumio volumio[835]: STREAMING PROXY: Client dropped request, destroying Dec 18 23:41:26 volumio volumio[835]: info: CoreCommandRouter::volumioPlay Dec 18 23:41:26 volumio volumio[835]: info: CoreStateMachine::play index 4 Dec 18 23:41:26 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:41:26 volumio volumio[835]: info: CoreStateMachine::stop Dec 18 23:41:26 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:41:26 volumio volumio[835]: info: CoreStateMachine::stPlaybackTimer Dec 18 23:41:26 volumio volumio[835]: info: CoreStateMachine::updateTrackBlock Dec 18 23:41:26 volumio volumio[835]: info: CorePlayQueue::getTrackBlock Dec 18 23:41:26 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:26 volumio volumio[835]: info: CorePlayQueue::getTrack 29 Dec 18 23:41:26 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:41:26 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:26 volumio volumio[835]: info: CoreStateMachine::serviceStop Dec 18 23:41:26 volumio volumio[835]: info: CorePlayQueue::getTrack 29 Dec 18 23:41:26 volumio volumio[835]: info: CoreCommandRouter::serviceStop Dec 18 23:41:26 volumio volumio[835]: info: [1766097686990] ControllerTidal::stop Dec 18 23:41:26 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:41:26 volumio volumio[835]: info: ControllerMpd::stop Dec 18 23:41:26 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 23:41:26 volumio volumio[835]: info: Dec 18 23:41:26 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:41:26 volumio volumio[835]: info: sendMpdCommand stop took 6 milliseconds Dec 18 23:41:26 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:41:26 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:41:26 volumio volumio[835]: info: CoreStateMachine::play index undefined Dec 18 23:41:26 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 23:41:26 volumio volumio[835]: info: CorePlayQueue::getTrack 4 Dec 18 23:41:26 volumio volumio[835]: info: CoreStateMachine::startPlaybackTimer Dec 18 23:41:26 volumio volumio[835]: info: CorePlayQueue::getTrack 4 Dec 18 23:41:26 volumio volumio[835]: info: [1766097686997] ControllerTidal::clearAddPlayTrack Dec 18 23:41:26 volumio volumio[835]: info: Getting stream with soundQuality LOSSLESS Dec 18 23:41:26 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 23:41:26 volumio volumio[835]: info: sendMpdCommand status took 1 milliseconds Dec 18 23:41:26 volumio volumio[835]: info: sendMpdCommand stop took 1 milliseconds Dec 18 23:41:26 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:41:26 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:41:26 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 23:41:26 volumio volumio[835]: info: Dec 18 23:41:26 volumio volumio[835]: ---------------------------- MPD announces system playlist update Dec 18 23:41:26 volumio volumio[835]: info: Ignoring MPD Status Update Dec 18 23:41:26 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:41:26 volumio volumio[835]: info: sendMpdCommand clear took 1 milliseconds Dec 18 23:41:26 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:41:27 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/345250732&soundQuality=LOSSLESS" Dec 18 23:41:27 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:41:27 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:41:27 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:27 volumio volumio[835]: info: CorePlayQueue::getTrack 4 Dec 18 23:41:27 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:41:27 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:27 volumio volumio[835]: info: CorePlayQueue::getTrack 4 Dec 18 23:41:27 volumio volumio[835]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Dec 18 23:41:27 volumio volumio[835]: info: ------------------------------ 8ms Dec 18 23:41:27 volumio volumio[835]: error: updateQueue error: null Dec 18 23:41:27 volumio volumio[835]: info: Dec 18 23:41:27 volumio volumio[835]: ---------------------------- MPD announces system playlist update Dec 18 23:41:27 volumio volumio[835]: info: Ignoring MPD Status Update Dec 18 23:41:27 volumio volumio[835]: info: ------------------------------ 5ms Dec 18 23:41:27 volumio volumio[835]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/345250732&soundQuality=LOSSLESS" took 4 milliseconds Dec 18 23:41:27 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 23:41:27 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand play Dec 18 23:41:27 volumio volumio[835]: STREAMING PROXY: Handling url /?data=tidal://song/345250732&soundQuality=LOSSLESS Dec 18 23:41:27 volumio volumio[835]: info: ------------------------------ 5ms Dec 18 23:41:27 volumio volumio[835]: info: sendMpdCommand play took 5 milliseconds Dec 18 23:41:27 volumio volumio[835]: info: Executing endpoint getStreamUrltidal Dec 18 23:41:27 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Dec 18 23:41:27 volumio volumio[835]: info: getStreamUrl took 212 milliseconds Dec 18 23:41:27 volumio volumio[835]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ0Y2Q5MGM5ODgwYzZiNzg1Njk2ZDE3MWRmYzY0NTRiZC5tcDQ/0.flac?token=1766101230~YzUxYzBkMmQzMThhOTk1ZDVmZTE1ODllMjZjMjdjZDQ5MDNjYmNlYQ== Dec 18 23:41:27 volumio volumio[835]: STREAMING PROXY: Response: 200, length: 15357222 Dec 18 23:41:27 volumio volumio[835]: info: Dec 18 23:41:27 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:41:27 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:41:27 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:41:27 volumio volumio[835]: info: Dec 18 23:41:27 volumio volumio[835]: ---------------------------- MPD announces state update: player Dec 18 23:41:27 volumio volumio[835]: info: sendMpdCommand status took 3 milliseconds Dec 18 23:41:27 volumio volumio[835]: info: ControllerMpd::getState Dec 18 23:41:27 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Dec 18 23:41:27 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:41:27 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:41:27 volumio volumio[835]: info: sendMpdCommand status took 1 milliseconds Dec 18 23:41:27 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:41:27 volumio volumio[835]: verbose: ControllerMpd::parseState Dec 18 23:41:27 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 23:41:27 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:41:27 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:41:27 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:41:27 volumio volumio[835]: info: CorePlayQueue::getTrack 4 Dec 18 23:41:27 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"345250732&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/345250732&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:41:27 volumio volumio[835]: verbose: CURRENT POSITION 4 Dec 18 23:41:27 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:41:27 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus stop Dec 18 23:41:27 volumio volumio[835]: info: ------------------------------ 5ms Dec 18 23:41:27 volumio volumio[835]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 23:41:27 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Dec 18 23:41:27 volumio volumio[835]: info: ControllerMpd::pushState Dec 18 23:41:27 volumio volumio[835]: info: CoreCommandRouter::servicePushState Dec 18 23:41:27 volumio volumio[835]: info: CorePlayQueue::getTrack 4 Dec 18 23:41:27 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"345250732&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/345250732&soundQuality=LOSSLESS","trackType":"tidal"} Dec 18 23:41:27 volumio volumio[835]: verbose: CURRENT POSITION 4 Dec 18 23:41:27 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Dec 18 23:41:27 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Dec 18 23:41:27 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Dec 18 23:41:27 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:27 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 23:41:27 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:27 volumio volumio[835]: info: CoreStateMachine::pushState Dec 18 23:41:27 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Dec 18 23:41:27 volumio volumio[835]: info: ------------------------------ 9ms Dec 18 23:41:29 volumio volumio[835]: STREAMING PROXY: Client dropped request, destroying Dec 18 23:41:44 volumio volumio[835]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 23:41:44 volumio volumio[835]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 23:41:44 volumio volumio[835]: info: Retrieving Cloud Streaming UI Dec 18 23:41:44 volumio volumio[835]: info: Getting Tidal Cloud Configuration Dec 18 23:41:44 volumio volumio[835]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 23:41:44 volumio volumio[835]: info: Getting Qobuz Cloud Configuration Dec 18 23:41:44 volumio volumio[835]: info: Asking plugin for UI Config Dec 18 23:41:44 volumio volumio[835]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 23:41:44 volumio volumio[835]: info: Getting Spotify Cloud Configuration Dec 18 23:41:44 volumio volumio[835]: info: Asking plugin for UI Config Dec 18 23:41:44 volumio volumio[835]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 23:41:44 volumio volumio[835]: info: Saving Spotify Acccount Dec 18 23:41:44 volumio volumio[835]: info: Got it Dec 18 23:41:44 volumio volumio[835]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 18 23:41:44 volumio volumio[835]: info: Got Tidal Cloud Configuration Dec 18 23:41:44 volumio volumio[835]: info: Got it Dec 18 23:41:44 volumio volumio[835]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 23:41:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 18 23:41:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 23:41:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 23:41:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 23:41:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 23:41:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 23:41:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 23:41:44 volumio volumio[835]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 23:41:44 volumio volumio[835]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 23:41:44 volumio volumio[835]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 23:41:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 23:41:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 18 23:41:45 volumio volumio[835]: info: Disabling MyMusic plugin upnp Dec 18 23:41:45 volumio sudo[23692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 18 23:41:45 volumio sudo[23692]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 23:41:45 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Dec 18 23:41:45 volumio volumio[835]: error: Upnp client error: Error: This socket has been ended by the other party Dec 18 23:41:45 volumio volumio[835]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 18 23:41:48 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 23:41:52 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 23:41:53 volumio volumio[835]: info: Enabling MyMusic plugin upnp Dec 18 23:41:53 volumio volumio[835]: info: Enabling plugin upnp Dec 18 23:41:53 volumio volumio[835]: info: Loading plugin "upnp"... Dec 18 23:41:53 volumio volumio[835]: info: [1766097713556] Starting Upmpd Daemon Dec 18 23:41:53 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 18 23:41:53 volumio volumio[835]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 23:41:53 volumio volumio[835]: Error: listen EADDRINUSE: address already in use :::6599 Dec 18 23:41:53 volumio volumio[835]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Dec 18 23:41:53 volumio volumio[835]: at listenInCluster (net.js:1379:12) Dec 18 23:41:53 volumio volumio[835]: at Server.listen (net.js:1465:7) Dec 18 23:41:53 volumio volumio[835]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Dec 18 23:41:53 volumio volumio[835]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Dec 18 23:41:53 volumio volumio[835]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Dec 18 23:41:53 volumio volumio[835]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 18 23:41:53 volumio volumio[835]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Dec 18 23:41:53 volumio volumio[835]: code: 'EADDRINUSE', Dec 18 23:41:53 volumio volumio[835]: errno: -98, Dec 18 23:41:53 volumio volumio[835]: syscall: 'listen', Dec 18 23:41:53 volumio volumio[835]: address: '::', Dec 18 23:41:53 volumio volumio[835]: port: 6599 Dec 18 23:41:53 volumio volumio[835]: } Dec 18 23:41:53 volumio volumio[835]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 23:41:53 volumio sudo[23721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-18 23:40 Dec 18 23:41:53 volumio sudo[23721]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 07:17:52 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="3b20d491450ccf59e0e519a97572dc60"