-- Logs begin at Thu 2024-11-21 19:00:55 UTC, end at Thu 2024-11-21 20:56:32 UTC. -- Nov 21 20:55:00 mb volumio[1064]: info: CoreCommandRouter::volumioPause Nov 21 20:55:00 mb volumio[1064]: info: CoreStateMachine::pause Nov 21 20:55:01 mb volumio[1064]: info: CoreCommandRouter::volumioRandom Nov 21 20:55:01 mb volumio[1064]: info: CoreCommandRouter::writePlayerControls Nov 21 20:55:01 mb volumio[1064]: info: CoreStateMachine::setRepeat true single false Nov 21 20:55:01 mb volumio[1064]: info: CoreStateMachine::pushState Nov 21 20:55:01 mb volumio[1064]: info: CoreCommandRouter::volumioPushState Nov 21 20:55:01 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:55:01 mb volumio[1064]: info: Updating push state because input changed Nov 21 20:55:01 mb volumio[1064]: info: CoreCommandRouter::volumiosetSourceActivempd Nov 21 20:55:01 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 20:55:02 mb volumio[1064]: info: CoreCommandRouter::volumioPause Nov 21 20:55:02 mb volumio[1064]: info: CoreStateMachine::pause Nov 21 20:55:10 mb volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 20:55:10 mb volumio[1064]: No protocol specified Nov 21 20:55:10 mb volumio[1064]: xcb_connection_has_error() returned true Nov 21 20:55:10 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 21 20:55:10 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 20:55:10 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 21 20:55:10 mb volumio[1064]: No protocol specified Nov 21 20:55:10 mb volumio[1064]: xcb_connection_has_error() returned true Nov 21 20:55:10 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 20:55:10 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 20:55:10 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 20:55:10 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 20:55:10 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 20:55:10 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 20:55:10 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 20:55:10 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 21 20:55:10 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 21 20:55:14 mb volumio[1064]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 21 20:55:14 mb volumio[1064]: info: CoreStateMachine::ClearQueue Nov 21 20:55:14 mb volumio[1064]: info: CoreStateMachine::stop Nov 21 20:55:14 mb volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 20:55:14 mb volumio[1064]: verbose: UNSET VOLATILE Nov 21 20:55:14 mb volumio[1064]: info: CoreStateMachine::updateTrackBlock Nov 21 20:55:14 mb volumio[1064]: info: CorePlayQueue::getTrackBlock Nov 21 20:55:14 mb volumio[1064]: info: CoreStateMachine::stPlaybackTimer Nov 21 20:55:14 mb volumio[1064]: info: CoreStateMachine::pushState Nov 21 20:55:14 mb volumio[1064]: info: CorePlayQueue::getTrack 6 Nov 21 20:55:14 mb volumio[1064]: info: CoreCommandRouter::volumioPushState Nov 21 20:55:14 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:55:14 mb volumio[1064]: info: CoreStateMachine::serviceStop Nov 21 20:55:14 mb volumio[1064]: info: CorePlayQueue::getTrack 6 Nov 21 20:55:14 mb volumio[1064]: info: CoreCommandRouter::serviceStop Nov 21 20:55:14 mb volumio[1064]: info: [1732222514008] ControllerTidal::stop Nov 21 20:55:14 mb volumio[1064]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 20:55:14 mb volumio[1064]: info: ControllerMpd::stop Nov 21 20:55:14 mb volumio[1064]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 20:55:14 mb volumio[1064]: info: CorePlayQueue::clearPlayQueue Nov 21 20:55:14 mb volumio[1064]: info: CorePlayQueue::saveQueue Nov 21 20:55:14 mb volumio[1064]: info: CoreCommandRouter::volumioPushState Nov 21 20:55:14 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:55:14 mb volumio[1064]: info: CoreCommandRouter::volumioPushQueue Nov 21 20:55:14 mb volumio[1064]: info: CoreStateMachine::addQueueItems Nov 21 20:55:14 mb volumio[1064]: info: CorePlayQueue::addQueueItems Nov 21 20:55:14 mb volumio[1064]: info: Adding Item to queue: tidal://song/22757088 Nov 21 20:55:14 mb volumio[1064]: info: Exploding uri tidal://song/22757088 in service tidal Nov 21 20:55:14 mb volumio[1064]: info: Adding Item to queue: tidal://song/306087740 Nov 21 20:55:14 mb volumio[1064]: info: Exploding uri tidal://song/306087740 in service tidal Nov 21 20:55:14 mb volumio[1064]: info: Adding Item to queue: tidal://song/1331956 Nov 21 20:55:14 mb volumio[1064]: info: Exploding uri tidal://song/1331956 in service tidal Nov 21 20:55:14 mb volumio[1064]: info: Adding Item to queue: tidal://song/1331953 Nov 21 20:55:14 mb volumio[1064]: info: Exploding uri tidal://song/1331953 in service tidal Nov 21 20:55:14 mb volumio[1064]: info: Adding Item to queue: tidal://song/64774494 Nov 21 20:55:14 mb volumio[1064]: info: Exploding uri tidal://song/64774494 in service tidal Nov 21 20:55:14 mb volumio[1064]: info: Adding Item to queue: tidal://song/76973652 Nov 21 20:55:14 mb volumio[1064]: info: Exploding uri tidal://song/76973652 in service tidal Nov 21 20:55:14 mb volumio[1064]: info: Adding Item to queue: tidal://song/1380907 Nov 21 20:55:14 mb volumio[1064]: info: Exploding uri tidal://song/1380907 in service tidal Nov 21 20:55:14 mb volumio[1064]: info: Updating push state because input changed Nov 21 20:55:14 mb volumio[1064]: info: CoreCommandRouter::volumiosetSourceActivempd Nov 21 20:55:14 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 20:55:14 mb volumio[1064]: info: Updating push state because input changed Nov 21 20:55:14 mb volumio[1064]: info: CoreCommandRouter::volumiosetSourceActivempd Nov 21 20:55:14 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 20:55:14 mb volumio[1064]: info: sendMpdCommand stop took 90 milliseconds Nov 21 20:55:14 mb volumio[1064]: info: explodeTIDALUri took 210 milliseconds Nov 21 20:55:14 mb volumio[1064]: info: explodeTIDALUri took 231 milliseconds Nov 21 20:55:14 mb volumio[1064]: info: explodeTIDALUri took 242 milliseconds Nov 21 20:55:14 mb volumio[1064]: info: explodeTIDALUri took 261 milliseconds Nov 21 20:55:14 mb volumio[1064]: info: explodeTIDALUri took 304 milliseconds Nov 21 20:55:14 mb volumio[1064]: info: explodeTIDALUri took 438 milliseconds Nov 21 20:55:14 mb volumio[1064]: info: explodeTIDALUri took 801 milliseconds Nov 21 20:55:14 mb volumio[1064]: info: CorePlayQueue::saveQueue Nov 21 20:55:14 mb volumio[1064]: info: CoreCommandRouter::volumioPushQueue Nov 21 20:55:14 mb volumio[1064]: info: CoreStateMachine::updateTrackBlock Nov 21 20:55:14 mb volumio[1064]: info: CorePlayQueue::getTrackBlock Nov 21 20:55:14 mb volumio[1064]: info: CoreCommandRouter::volumioPlay Nov 21 20:55:14 mb volumio[1064]: verbose: UNSET VOLATILE Nov 21 20:55:14 mb volumio[1064]: info: CoreStateMachine::play index 2 Nov 21 20:55:14 mb volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 20:55:14 mb volumio[1064]: info: CoreStateMachine::stop Nov 21 20:55:14 mb volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 20:55:14 mb volumio[1064]: verbose: UNSET VOLATILE Nov 21 20:55:14 mb volumio[1064]: info: CoreStateMachine::play index undefined Nov 21 20:55:14 mb volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 20:55:14 mb volumio[1064]: info: CorePlayQueue::getTrack 2 Nov 21 20:55:14 mb volumio[1064]: info: CoreStateMachine::startPlaybackTimer Nov 21 20:55:14 mb volumio[1064]: info: CorePlayQueue::getTrack 2 Nov 21 20:55:14 mb volumio[1064]: info: [1732222514849] ControllerTidal::clearAddPlayTrack Nov 21 20:55:14 mb volumio[1064]: info: Getting stream with soundQuality LOSSLESS Nov 21 20:55:15 mb volumio[1064]: info: getStreamUrl took 382 milliseconds Nov 21 20:55:15 mb volumio[1064]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 20:55:15 mb volumio[1064]: info: sendMpdCommand stop took 2 milliseconds Nov 21 20:55:15 mb volumio[1064]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 20:55:15 mb volumio[1064]: info: Nov 21 20:55:15 mb volumio[1064]: ---------------------------- MPD announces system playlist update Nov 21 20:55:15 mb volumio[1064]: info: Ignoring MPD Status Update Nov 21 20:55:15 mb volumio[1064]: info: sendMpdCommand clear took 6 milliseconds Nov 21 20:55:15 mb volumio[1064]: verbose: ControllerMpd::sendMpdCommand add "http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEidmYmJiNDVhNGIyYzhmYjI4ZTkyNzMyODI0Yzk4Y2ViN182MS5tcDQ/0.flac?Expires=1732226115&Signature=T~tiOL9SX7YNqnlVyc2ZWFiGJ-fd1ng9WGaPMZ8HloJ2t-xGohKyFhF6uALhnpODVxDTtChvLGwslQaxc134Ct51qCev7UXzdweChrgIxjOWG-ruQIwiteWe-aAL2xpNJXtF7--c9pkynC2Yp8d9BeKze4WS9P0uUPuPk1nwyNnFDYsqIqWvjRl5lhuWIuDWfLiZutIhNQ3Gwe4Z~L1upekYKqcz3xDlA3h5onyYGasenFIpNL2s~U-EFy4y6jMfVWCvQfcNJDePVbt5cdSYSPWfZOPlglOTf9ZYqO7HLU8KdIEjZ1pO3rI0LEmlOw5DhvottnZnwU4Yi9MgRCZREg__&Key-Pair-Id=K14LZCZ9QUI4JL" Nov 21 20:55:15 mb volumio[1064]: error: updateQueue error: null Nov 21 20:55:15 mb volumio[1064]: info: Nov 21 20:55:15 mb volumio[1064]: ---------------------------- MPD announces system playlist update Nov 21 20:55:15 mb volumio[1064]: info: Ignoring MPD Status Update Nov 21 20:55:15 mb volumio[1064]: info: ------------------------------ 16ms Nov 21 20:55:15 mb volumio[1064]: info: sendMpdCommand add "http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEidmYmJiNDVhNGIyYzhmYjI4ZTkyNzMyODI0Yzk4Y2ViN182MS5tcDQ/0.flac?Expires=1732226115&Signature=T~tiOL9SX7YNqnlVyc2ZWFiGJ-fd1ng9WGaPMZ8HloJ2t-xGohKyFhF6uALhnpODVxDTtChvLGwslQaxc134Ct51qCev7UXzdweChrgIxjOWG-ruQIwiteWe-aAL2xpNJXtF7--c9pkynC2Yp8d9BeKze4WS9P0uUPuPk1nwyNnFDYsqIqWvjRl5lhuWIuDWfLiZutIhNQ3Gwe4Z~L1upekYKqcz3xDlA3h5onyYGasenFIpNL2s~U-EFy4y6jMfVWCvQfcNJDePVbt5cdSYSPWfZOPlglOTf9ZYqO7HLU8KdIEjZ1pO3rI0LEmlOw5DhvottnZnwU4Yi9MgRCZREg__&Key-Pair-Id=K14LZCZ9QUI4JL" took 3 milliseconds Nov 21 20:55:15 mb volumio[1064]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 20:55:15 mb volumio[1064]: verbose: ControllerMpd::sendMpdCommand play Nov 21 20:55:15 mb volumio[1064]: info: ------------------------------ 12ms Nov 21 20:55:15 mb volumio[1064]: info: sendMpdCommand play took 10 milliseconds Nov 21 20:55:15 mb volumio[1064]: info: Nov 21 20:55:15 mb volumio[1064]: ---------------------------- MPD announces state update: player Nov 21 20:55:15 mb volumio[1064]: info: ControllerMpd::getState Nov 21 20:55:15 mb volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Nov 21 20:55:15 mb volumio[1064]: info: Nov 21 20:55:15 mb volumio[1064]: ---------------------------- MPD announces state update: player Nov 21 20:55:15 mb volumio[1064]: info: sendMpdCommand status took 22 milliseconds Nov 21 20:55:15 mb volumio[1064]: info: ControllerMpd::getState Nov 21 20:55:15 mb volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Nov 21 20:55:15 mb volumio[1064]: verbose: ControllerMpd::parseState Nov 21 20:55:15 mb volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 20:55:15 mb volumio[1064]: info: sendMpdCommand status took 7 milliseconds Nov 21 20:55:15 mb volumio[1064]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 21 20:55:15 mb volumio[1064]: verbose: ControllerMpd::parseState Nov 21 20:55:15 mb volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 20:55:15 mb volumio[1064]: verbose: ControllerMpd::parseTrackInfo Nov 21 20:55:15 mb volumio[1064]: info: ControllerMpd::pushState Nov 21 20:55:15 mb volumio[1064]: info: CoreCommandRouter::servicePushState Nov 21 20:55:15 mb volumio[1064]: info: CorePlayQueue::getTrack 2 Nov 21 20:55:15 mb volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"622 Kbps","isStreaming":false,"title":"0.flac?Expires=1732226115&Signature=T~tiOL9SX7YNqnlVyc2ZWFiGJ-fd1ng9WGaPMZ8HloJ2t-xGohKyFhF6uALhnpODVxDTtChvLGwslQaxc134Ct51qCev7UXzdweChrgIxjOWG-ruQIwiteWe-aAL2xpNJXtF7--c9pkynC2Yp8d9BeKze4WS9P0uUPuPk1nwyNnFDYsqIqWvjRl5lhuWIuDWfLiZutIhNQ3Gwe4Z~L1upekYKqcz3xDlA3h5onyYGasenFIpNL2s~U-EFy4y6jMfVWCvQfcNJDePVbt5cdSYSPWfZOPlglOTf9ZYqO7HLU8KdIEjZ1pO3rI0LEmlOw5DhvottnZnwU4Yi9MgRCZREg__&Key-Pair-Id=K14LZCZ9QUI4JL","artist":null,"album":null,"uri":"http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEidmYmJiNDVhNGIyYzhmYjI4ZTkyNzMyODI0Yzk4Y2ViN182MS5tcDQ/0.flac?Expires=1732226115&Signature=T~tiOL9SX7YNqnlVyc2ZWFiGJ-fd1ng9WGaPMZ8HloJ2t-xGohKyFhF6uALhnpODVxDTtChvLGwslQaxc134Ct51qCev7UXzdweChrgIxjOWG-ruQIwiteWe-aAL2xpNJXtF7--c9pkynC2Yp8d9BeKze4WS9P0uUPuPk1nwyNnFDYsqIqWvjRl5lhuWIuDWfLiZutIhNQ3Gwe4Z~L1upekYKqcz3xDlA3h5onyYGasenFIpNL2s~U-EFy4y6jMfVWCvQfcNJDePVbt5cdSYSPWfZOPlglOTf9ZYqO7HLU8KdIEjZ1pO3rI0LEmlOw5DhvottnZnwU4Yi9MgRCZREg__&Key-Pair-Id=K14LZCZ9QUI4JL","trackType":"tidal"} Nov 21 20:55:15 mb volumio[1064]: verbose: CURRENT POSITION 2 Nov 21 20:55:15 mb volumio[1064]: info: CoreStateMachine::syncState stateService play Nov 21 20:55:15 mb volumio[1064]: info: CoreStateMachine::syncState currentStatus stop Nov 21 20:55:15 mb volumio[1064]: info: CoreStateMachine::pushState Nov 21 20:55:15 mb volumio[1064]: info: CoreCommandRouter::volumioPushState Nov 21 20:55:15 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:55:15 mb volumio[1064]: info: ------------------------------ 65ms Nov 21 20:55:15 mb volumio[1064]: info: sendMpdCommand playlistinfo took 30 milliseconds Nov 21 20:55:15 mb volumio[1064]: verbose: ControllerMpd::parseTrackInfo Nov 21 20:55:15 mb volumio[1064]: info: ControllerMpd::pushState Nov 21 20:55:15 mb volumio[1064]: info: CoreCommandRouter::servicePushState Nov 21 20:55:15 mb volumio[1064]: info: CorePlayQueue::getTrack 2 Nov 21 20:55:15 mb volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"459 Kbps","isStreaming":false,"title":"0.flac?Expires=1732226115&Signature=T~tiOL9SX7YNqnlVyc2ZWFiGJ-fd1ng9WGaPMZ8HloJ2t-xGohKyFhF6uALhnpODVxDTtChvLGwslQaxc134Ct51qCev7UXzdweChrgIxjOWG-ruQIwiteWe-aAL2xpNJXtF7--c9pkynC2Yp8d9BeKze4WS9P0uUPuPk1nwyNnFDYsqIqWvjRl5lhuWIuDWfLiZutIhNQ3Gwe4Z~L1upekYKqcz3xDlA3h5onyYGasenFIpNL2s~U-EFy4y6jMfVWCvQfcNJDePVbt5cdSYSPWfZOPlglOTf9ZYqO7HLU8KdIEjZ1pO3rI0LEmlOw5DhvottnZnwU4Yi9MgRCZREg__&Key-Pair-Id=K14LZCZ9QUI4JL","artist":null,"album":null,"uri":"http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEidmYmJiNDVhNGIyYzhmYjI4ZTkyNzMyODI0Yzk4Y2ViN182MS5tcDQ/0.flac?Expires=1732226115&Signature=T~tiOL9SX7YNqnlVyc2ZWFiGJ-fd1ng9WGaPMZ8HloJ2t-xGohKyFhF6uALhnpODVxDTtChvLGwslQaxc134Ct51qCev7UXzdweChrgIxjOWG-ruQIwiteWe-aAL2xpNJXtF7--c9pkynC2Yp8d9BeKze4WS9P0uUPuPk1nwyNnFDYsqIqWvjRl5lhuWIuDWfLiZutIhNQ3Gwe4Z~L1upekYKqcz3xDlA3h5onyYGasenFIpNL2s~U-EFy4y6jMfVWCvQfcNJDePVbt5cdSYSPWfZOPlglOTf9ZYqO7HLU8KdIEjZ1pO3rI0LEmlOw5DhvottnZnwU4Yi9MgRCZREg__&Key-Pair-Id=K14LZCZ9QUI4JL","trackType":"tidal"} Nov 21 20:55:15 mb volumio[1064]: verbose: CURRENT POSITION 2 Nov 21 20:55:15 mb volumio[1064]: info: CoreStateMachine::syncState stateService play Nov 21 20:55:15 mb volumio[1064]: info: CoreStateMachine::syncState currentStatus play Nov 21 20:55:15 mb volumio[1064]: info: Received an update from plugin. extracting info from payload Nov 21 20:55:15 mb volumio[1064]: info: CoreStateMachine::pushState Nov 21 20:55:15 mb volumio[1064]: info: CoreCommandRouter::volumioPushState Nov 21 20:55:15 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:55:15 mb volumio[1064]: info: CoreStateMachine::pushState Nov 21 20:55:15 mb volumio[1064]: info: CoreCommandRouter::volumioPushState Nov 21 20:55:15 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:55:15 mb volumio[1064]: info: ------------------------------ 87ms Nov 21 20:55:16 mb volumio[1064]: info: Updating push state because input changed Nov 21 20:55:16 mb volumio[1064]: info: CoreCommandRouter::volumiosetSourceActivempd Nov 21 20:55:16 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 20:55:40 mb volumio[1064]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::ClearQueue Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::stop Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 20:55:40 mb volumio[1064]: verbose: UNSET VOLATILE Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::stPlaybackTimer Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::updateTrackBlock Nov 21 20:55:40 mb volumio[1064]: info: CorePlayQueue::getTrackBlock Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::pushState Nov 21 20:55:40 mb volumio[1064]: info: CorePlayQueue::getTrack 2 Nov 21 20:55:40 mb volumio[1064]: info: CoreCommandRouter::volumioPushState Nov 21 20:55:40 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::serviceStop Nov 21 20:55:40 mb volumio[1064]: info: CorePlayQueue::getTrack 2 Nov 21 20:55:40 mb volumio[1064]: info: CoreCommandRouter::serviceStop Nov 21 20:55:40 mb volumio[1064]: info: [1732222540109] ControllerTidal::stop Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 20:55:40 mb volumio[1064]: info: ControllerMpd::stop Nov 21 20:55:40 mb volumio[1064]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 20:55:40 mb volumio[1064]: info: CorePlayQueue::clearPlayQueue Nov 21 20:55:40 mb volumio[1064]: info: CorePlayQueue::saveQueue Nov 21 20:55:40 mb volumio[1064]: info: CoreCommandRouter::volumioPushState Nov 21 20:55:40 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:55:40 mb volumio[1064]: info: CoreCommandRouter::volumioPushQueue Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::addQueueItems Nov 21 20:55:40 mb volumio[1064]: info: CorePlayQueue::addQueueItems Nov 21 20:55:40 mb volumio[1064]: info: Adding Item to queue: tidal://song/22757088 Nov 21 20:55:40 mb volumio[1064]: info: Exploding uri tidal://song/22757088 in service tidal Nov 21 20:55:40 mb volumio[1064]: info: Adding Item to queue: tidal://song/306087740 Nov 21 20:55:40 mb volumio[1064]: info: Exploding uri tidal://song/306087740 in service tidal Nov 21 20:55:40 mb volumio[1064]: info: Adding Item to queue: tidal://song/1331956 Nov 21 20:55:40 mb volumio[1064]: info: Exploding uri tidal://song/1331956 in service tidal Nov 21 20:55:40 mb volumio[1064]: info: Adding Item to queue: tidal://song/1331953 Nov 21 20:55:40 mb volumio[1064]: info: Exploding uri tidal://song/1331953 in service tidal Nov 21 20:55:40 mb volumio[1064]: info: Adding Item to queue: tidal://song/64774494 Nov 21 20:55:40 mb volumio[1064]: info: Exploding uri tidal://song/64774494 in service tidal Nov 21 20:55:40 mb volumio[1064]: info: Adding Item to queue: tidal://song/76973652 Nov 21 20:55:40 mb volumio[1064]: info: Exploding uri tidal://song/76973652 in service tidal Nov 21 20:55:40 mb volumio[1064]: info: Adding Item to queue: tidal://song/1380907 Nov 21 20:55:40 mb volumio[1064]: info: Exploding uri tidal://song/1380907 in service tidal Nov 21 20:55:40 mb volumio[1064]: info: Updating push state because input changed Nov 21 20:55:40 mb volumio[1064]: info: CoreCommandRouter::volumiosetSourceActivempd Nov 21 20:55:40 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 20:55:40 mb volumio[1064]: info: Nov 21 20:55:40 mb volumio[1064]: ---------------------------- MPD announces state update: player Nov 21 20:55:40 mb volumio[1064]: info: sendMpdCommand stop took 88 milliseconds Nov 21 20:55:40 mb volumio[1064]: info: ControllerMpd::getState Nov 21 20:55:40 mb volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Nov 21 20:55:40 mb volumio[1064]: info: sendMpdCommand status took 5 milliseconds Nov 21 20:55:40 mb volumio[1064]: verbose: ControllerMpd::parseState Nov 21 20:55:40 mb volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 20:55:40 mb volumio[1064]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 21 20:55:40 mb volumio[1064]: verbose: ControllerMpd::parseTrackInfo Nov 21 20:55:40 mb volumio[1064]: info: ControllerMpd::pushState Nov 21 20:55:40 mb volumio[1064]: info: CoreCommandRouter::servicePushState Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::pushState Nov 21 20:55:40 mb volumio[1064]: info: CoreCommandRouter::volumioPushState Nov 21 20:55:40 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:55:40 mb volumio[1064]: info: CorePlayQueue::getTrack 2 Nov 21 20:55:40 mb volumio[1064]: 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":"0.flac?Expires=1732226115&Signature=T~tiOL9SX7YNqnlVyc2ZWFiGJ-fd1ng9WGaPMZ8HloJ2t-xGohKyFhF6uALhnpODVxDTtChvLGwslQaxc134Ct51qCev7UXzdweChrgIxjOWG-ruQIwiteWe-aAL2xpNJXtF7--c9pkynC2Yp8d9BeKze4WS9P0uUPuPk1nwyNnFDYsqIqWvjRl5lhuWIuDWfLiZutIhNQ3Gwe4Z~L1upekYKqcz3xDlA3h5onyYGasenFIpNL2s~U-EFy4y6jMfVWCvQfcNJDePVbt5cdSYSPWfZOPlglOTf9ZYqO7HLU8KdIEjZ1pO3rI0LEmlOw5DhvottnZnwU4Yi9MgRCZREg__&Key-Pair-Id=K14LZCZ9QUI4JL","artist":null,"album":null,"uri":"http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEidmYmJiNDVhNGIyYzhmYjI4ZTkyNzMyODI0Yzk4Y2ViN182MS5tcDQ/0.flac?Expires=1732226115&Signature=T~tiOL9SX7YNqnlVyc2ZWFiGJ-fd1ng9WGaPMZ8HloJ2t-xGohKyFhF6uALhnpODVxDTtChvLGwslQaxc134Ct51qCev7UXzdweChrgIxjOWG-ruQIwiteWe-aAL2xpNJXtF7--c9pkynC2Yp8d9BeKze4WS9P0uUPuPk1nwyNnFDYsqIqWvjRl5lhuWIuDWfLiZutIhNQ3Gwe4Z~L1upekYKqcz3xDlA3h5onyYGasenFIpNL2s~U-EFy4y6jMfVWCvQfcNJDePVbt5cdSYSPWfZOPlglOTf9ZYqO7HLU8KdIEjZ1pO3rI0LEmlOw5DhvottnZnwU4Yi9MgRCZREg__&Key-Pair-Id=K14LZCZ9QUI4JL","trackType":"tidal"} Nov 21 20:55:40 mb volumio[1064]: verbose: CURRENT POSITION 2 Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::syncState stateService stop Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::syncState currentStatus stop Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::pushState Nov 21 20:55:40 mb volumio[1064]: info: CoreCommandRouter::volumioPushState Nov 21 20:55:40 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:55:40 mb volumio[1064]: info: No code Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::pushState Nov 21 20:55:40 mb volumio[1064]: info: CoreCommandRouter::volumioPushState Nov 21 20:55:40 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:55:40 mb volumio[1064]: info: ------------------------------ 47ms Nov 21 20:55:40 mb volumio[1064]: info: explodeTIDALUri took 212 milliseconds Nov 21 20:55:40 mb volumio[1064]: info: explodeTIDALUri took 213 milliseconds Nov 21 20:55:40 mb volumio[1064]: info: explodeTIDALUri took 233 milliseconds Nov 21 20:55:40 mb volumio[1064]: info: explodeTIDALUri took 255 milliseconds Nov 21 20:55:40 mb volumio[1064]: info: explodeTIDALUri took 261 milliseconds Nov 21 20:55:40 mb volumio[1064]: info: explodeTIDALUri took 268 milliseconds Nov 21 20:55:40 mb volumio[1064]: info: explodeTIDALUri took 819 milliseconds Nov 21 20:55:40 mb volumio[1064]: info: CorePlayQueue::saveQueue Nov 21 20:55:40 mb volumio[1064]: info: CoreCommandRouter::volumioPushQueue Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::updateTrackBlock Nov 21 20:55:40 mb volumio[1064]: info: CorePlayQueue::getTrackBlock Nov 21 20:55:40 mb volumio[1064]: info: CoreCommandRouter::volumioPlay Nov 21 20:55:40 mb volumio[1064]: verbose: UNSET VOLATILE Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::play index 3 Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::stop Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 20:55:40 mb volumio[1064]: verbose: UNSET VOLATILE Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::play index undefined Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 20:55:40 mb volumio[1064]: info: CorePlayQueue::getTrack 3 Nov 21 20:55:40 mb volumio[1064]: info: CoreStateMachine::startPlaybackTimer Nov 21 20:55:40 mb volumio[1064]: info: CorePlayQueue::getTrack 3 Nov 21 20:55:40 mb volumio[1064]: info: [1732222540950] ControllerTidal::clearAddPlayTrack Nov 21 20:55:40 mb volumio[1064]: info: Getting stream with soundQuality LOSSLESS Nov 21 20:55:41 mb volumio[1064]: info: getStreamUrl took 355 milliseconds Nov 21 20:55:41 mb volumio[1064]: verbose: ControllerMpd::sendMpdCommand stop Nov 21 20:55:41 mb volumio[1064]: info: sendMpdCommand stop took 2 milliseconds Nov 21 20:55:41 mb volumio[1064]: verbose: ControllerMpd::sendMpdCommand clear Nov 21 20:55:41 mb volumio[1064]: info: Nov 21 20:55:41 mb volumio[1064]: ---------------------------- MPD announces system playlist update Nov 21 20:55:41 mb volumio[1064]: info: Ignoring MPD Status Update Nov 21 20:55:41 mb volumio[1064]: info: sendMpdCommand clear took 3 milliseconds Nov 21 20:55:41 mb volumio[1064]: verbose: ControllerMpd::sendMpdCommand add "http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic2NTUyMGUwMTM4Y2RmODA1ZmRkNTU2NDIyY2YyNDJmNF82MS5tcDQ/0.flac?Expires=1732226054&Signature=FWkpHFGI83I5jj9AcDhH33L7BYc0XjRz8PrAww-2vJiSQMGRDgFj79ecIEwOePHOpNWbhjI7OEN1xApsedR7nKDH72tyf-6ma960UmI5Hb51XBGr5bUJQYxDx9p-JD1wkRJyzzdf~gW3lR1FwjxmV-8oE9rE3jwiOg0oCTDcbIFlBt7AolYfV0fNTlVOOhFDoGUu1wGSYYgLu1VLi6P8ISGP-9SNASR0eUn~QhOkyoz6nCls3r1LyEBnW5ygvSSgQotcel85Ia-LZry3VAfFs4NfpFMl1~HmhVxrBP6prUT3DrRBOWyMm6cr3IOhX2Kahl3ZGxkLe8GRaTH8zxxVAw__&Key-Pair-Id=K14LZCZ9QUI4JL" Nov 21 20:55:41 mb volumio[1064]: error: updateQueue error: null Nov 21 20:55:41 mb volumio[1064]: info: Nov 21 20:55:41 mb volumio[1064]: ---------------------------- MPD announces system playlist update Nov 21 20:55:41 mb volumio[1064]: info: Ignoring MPD Status Update Nov 21 20:55:41 mb volumio[1064]: info: ------------------------------ 5ms Nov 21 20:55:41 mb volumio[1064]: info: sendMpdCommand add "http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic2NTUyMGUwMTM4Y2RmODA1ZmRkNTU2NDIyY2YyNDJmNF82MS5tcDQ/0.flac?Expires=1732226054&Signature=FWkpHFGI83I5jj9AcDhH33L7BYc0XjRz8PrAww-2vJiSQMGRDgFj79ecIEwOePHOpNWbhjI7OEN1xApsedR7nKDH72tyf-6ma960UmI5Hb51XBGr5bUJQYxDx9p-JD1wkRJyzzdf~gW3lR1FwjxmV-8oE9rE3jwiOg0oCTDcbIFlBt7AolYfV0fNTlVOOhFDoGUu1wGSYYgLu1VLi6P8ISGP-9SNASR0eUn~QhOkyoz6nCls3r1LyEBnW5ygvSSgQotcel85Ia-LZry3VAfFs4NfpFMl1~HmhVxrBP6prUT3DrRBOWyMm6cr3IOhX2Kahl3ZGxkLe8GRaTH8zxxVAw__&Key-Pair-Id=K14LZCZ9QUI4JL" took 3 milliseconds Nov 21 20:55:41 mb volumio[1064]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 21 20:55:41 mb volumio[1064]: verbose: ControllerMpd::sendMpdCommand play Nov 21 20:55:41 mb volumio[1064]: info: ------------------------------ 5ms Nov 21 20:55:41 mb volumio[1064]: info: sendMpdCommand play took 3 milliseconds Nov 21 20:55:41 mb volumio[1064]: info: Nov 21 20:55:41 mb volumio[1064]: ---------------------------- MPD announces state update: player Nov 21 20:55:41 mb volumio[1064]: info: ControllerMpd::getState Nov 21 20:55:41 mb volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Nov 21 20:55:41 mb volumio[1064]: info: Nov 21 20:55:41 mb volumio[1064]: ---------------------------- MPD announces state update: player Nov 21 20:55:41 mb volumio[1064]: info: sendMpdCommand status took 6 milliseconds Nov 21 20:55:41 mb volumio[1064]: info: ControllerMpd::getState Nov 21 20:55:41 mb volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Nov 21 20:55:41 mb volumio[1064]: verbose: ControllerMpd::parseState Nov 21 20:55:41 mb volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 20:55:41 mb volumio[1064]: info: sendMpdCommand status took 7 milliseconds Nov 21 20:55:41 mb volumio[1064]: info: sendMpdCommand playlistinfo took 8 milliseconds Nov 21 20:55:41 mb volumio[1064]: verbose: ControllerMpd::parseState Nov 21 20:55:41 mb volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 21 20:55:41 mb volumio[1064]: verbose: ControllerMpd::parseTrackInfo Nov 21 20:55:41 mb volumio[1064]: info: ControllerMpd::pushState Nov 21 20:55:41 mb volumio[1064]: info: CoreCommandRouter::servicePushState Nov 21 20:55:41 mb volumio[1064]: info: CorePlayQueue::getTrack 3 Nov 21 20:55:41 mb volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":370,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"652 Kbps","isStreaming":false,"title":"0.flac?Expires=1732226054&Signature=FWkpHFGI83I5jj9AcDhH33L7BYc0XjRz8PrAww-2vJiSQMGRDgFj79ecIEwOePHOpNWbhjI7OEN1xApsedR7nKDH72tyf-6ma960UmI5Hb51XBGr5bUJQYxDx9p-JD1wkRJyzzdf~gW3lR1FwjxmV-8oE9rE3jwiOg0oCTDcbIFlBt7AolYfV0fNTlVOOhFDoGUu1wGSYYgLu1VLi6P8ISGP-9SNASR0eUn~QhOkyoz6nCls3r1LyEBnW5ygvSSgQotcel85Ia-LZry3VAfFs4NfpFMl1~HmhVxrBP6prUT3DrRBOWyMm6cr3IOhX2Kahl3ZGxkLe8GRaTH8zxxVAw__&Key-Pair-Id=K14LZCZ9QUI4JL","artist":null,"album":null,"uri":"http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic2NTUyMGUwMTM4Y2RmODA1ZmRkNTU2NDIyY2YyNDJmNF82MS5tcDQ/0.flac?Expires=1732226054&Signature=FWkpHFGI83I5jj9AcDhH33L7BYc0XjRz8PrAww-2vJiSQMGRDgFj79ecIEwOePHOpNWbhjI7OEN1xApsedR7nKDH72tyf-6ma960UmI5Hb51XBGr5bUJQYxDx9p-JD1wkRJyzzdf~gW3lR1FwjxmV-8oE9rE3jwiOg0oCTDcbIFlBt7AolYfV0fNTlVOOhFDoGUu1wGSYYgLu1VLi6P8ISGP-9SNASR0eUn~QhOkyoz6nCls3r1LyEBnW5ygvSSgQotcel85Ia-LZry3VAfFs4NfpFMl1~HmhVxrBP6prUT3DrRBOWyMm6cr3IOhX2Kahl3ZGxkLe8GRaTH8zxxVAw__&Key-Pair-Id=K14LZCZ9QUI4JL","trackType":"tidal"} Nov 21 20:55:41 mb volumio[1064]: verbose: CURRENT POSITION 3 Nov 21 20:55:41 mb volumio[1064]: info: CoreStateMachine::syncState stateService play Nov 21 20:55:41 mb volumio[1064]: info: CoreStateMachine::syncState currentStatus stop Nov 21 20:55:41 mb volumio[1064]: info: CoreStateMachine::pushState Nov 21 20:55:41 mb volumio[1064]: info: CoreCommandRouter::volumioPushState Nov 21 20:55:41 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:55:41 mb volumio[1064]: info: ------------------------------ 42ms Nov 21 20:55:41 mb volumio[1064]: info: sendMpdCommand playlistinfo took 24 milliseconds Nov 21 20:55:41 mb volumio[1064]: verbose: ControllerMpd::parseTrackInfo Nov 21 20:55:41 mb volumio[1064]: info: ControllerMpd::pushState Nov 21 20:55:41 mb volumio[1064]: info: CoreCommandRouter::servicePushState Nov 21 20:55:41 mb volumio[1064]: info: CorePlayQueue::getTrack 3 Nov 21 20:55:41 mb volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":370,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"895 Kbps","isStreaming":false,"title":"0.flac?Expires=1732226054&Signature=FWkpHFGI83I5jj9AcDhH33L7BYc0XjRz8PrAww-2vJiSQMGRDgFj79ecIEwOePHOpNWbhjI7OEN1xApsedR7nKDH72tyf-6ma960UmI5Hb51XBGr5bUJQYxDx9p-JD1wkRJyzzdf~gW3lR1FwjxmV-8oE9rE3jwiOg0oCTDcbIFlBt7AolYfV0fNTlVOOhFDoGUu1wGSYYgLu1VLi6P8ISGP-9SNASR0eUn~QhOkyoz6nCls3r1LyEBnW5ygvSSgQotcel85Ia-LZry3VAfFs4NfpFMl1~HmhVxrBP6prUT3DrRBOWyMm6cr3IOhX2Kahl3ZGxkLe8GRaTH8zxxVAw__&Key-Pair-Id=K14LZCZ9QUI4JL","artist":null,"album":null,"uri":"http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic2NTUyMGUwMTM4Y2RmODA1ZmRkNTU2NDIyY2YyNDJmNF82MS5tcDQ/0.flac?Expires=1732226054&Signature=FWkpHFGI83I5jj9AcDhH33L7BYc0XjRz8PrAww-2vJiSQMGRDgFj79ecIEwOePHOpNWbhjI7OEN1xApsedR7nKDH72tyf-6ma960UmI5Hb51XBGr5bUJQYxDx9p-JD1wkRJyzzdf~gW3lR1FwjxmV-8oE9rE3jwiOg0oCTDcbIFlBt7AolYfV0fNTlVOOhFDoGUu1wGSYYgLu1VLi6P8ISGP-9SNASR0eUn~QhOkyoz6nCls3r1LyEBnW5ygvSSgQotcel85Ia-LZry3VAfFs4NfpFMl1~HmhVxrBP6prUT3DrRBOWyMm6cr3IOhX2Kahl3ZGxkLe8GRaTH8zxxVAw__&Key-Pair-Id=K14LZCZ9QUI4JL","trackType":"tidal"} Nov 21 20:55:41 mb volumio[1064]: verbose: CURRENT POSITION 3 Nov 21 20:55:41 mb volumio[1064]: info: CoreStateMachine::syncState stateService play Nov 21 20:55:41 mb volumio[1064]: info: CoreStateMachine::syncState currentStatus play Nov 21 20:55:41 mb volumio[1064]: info: Received an update from plugin. extracting info from payload Nov 21 20:55:41 mb volumio[1064]: info: CoreStateMachine::pushState Nov 21 20:55:41 mb volumio[1064]: info: CoreCommandRouter::volumioPushState Nov 21 20:55:41 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:55:41 mb volumio[1064]: info: CoreStateMachine::pushState Nov 21 20:55:41 mb volumio[1064]: info: CoreCommandRouter::volumioPushState Nov 21 20:55:41 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 20:55:41 mb volumio[1064]: info: ------------------------------ 65ms Nov 21 20:55:41 mb volumio[1064]: info: Updating push state because input changed Nov 21 20:55:41 mb volumio[1064]: info: CoreCommandRouter::volumiosetSourceActivempd Nov 21 20:55:41 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 20:55:54 mb volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 20:55:54 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 21 20:55:54 mb volumio[1064]: info: Received Get System Version Nov 21 20:55:54 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 20:56:04 mb volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 20:56:04 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Nov 21 20:56:11 mb volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 20:56:11 mb volumio[1064]: No protocol specified Nov 21 20:56:11 mb volumio[1064]: xcb_connection_has_error() returned true Nov 21 20:56:11 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 21 20:56:11 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 20:56:11 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 21 20:56:12 mb volumio[1064]: No protocol specified Nov 21 20:56:12 mb volumio[1064]: xcb_connection_has_error() returned true Nov 21 20:56:12 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 20:56:12 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 20:56:12 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 20:56:12 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 20:56:12 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 20:56:12 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 20:56:12 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 20:56:12 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 21 20:56:12 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 21 20:56:26 mb volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 20:56:26 mb volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 20:56:26 mb volumio[1064]: info: Retrieving Cloud Streaming UI Nov 21 20:56:26 mb volumio[1064]: info: Getting Tidal Cloud Configuration Nov 21 20:56:26 mb volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 20:56:26 mb volumio[1064]: info: Getting Qobuz Cloud Configuration Nov 21 20:56:26 mb volumio[1064]: info: Asking plugin for UI Config Nov 21 20:56:26 mb volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 20:56:26 mb volumio[1064]: info: Getting Spotify Cloud Configuration Nov 21 20:56:26 mb volumio[1064]: info: Asking plugin for UI Config Nov 21 20:56:26 mb volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 20:56:26 mb volumio[1064]: error: Could not retrieve plugin music_service spotify Nov 21 20:56:26 mb volumio[1064]: info: Saving Spotify Acccount Nov 21 20:56:26 mb volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 20:56:26 mb volumio[1064]: info: Got it Nov 21 20:56:26 mb volumio[1064]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 21 20:56:26 mb volumio[1064]: info: Got Tidal Cloud Configuration Nov 21 20:56:26 mb volumio[1064]: info: Got it Nov 21 20:56:26 mb volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 20:56:26 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 21 20:56:26 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 21 20:56:26 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 21 20:56:26 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 21 20:56:26 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 20:56:26 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 20:56:26 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 20:56:26 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 20:56:26 mb volumio[1064]: info: CoreCommandRouter::volumioGetBrowseSources Nov 21 20:56:26 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 21 20:56:26 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 21 20:56:29 mb volumio[1064]: info: Disabling MyMusic plugin upnp Nov 21 20:56:29 mb sudo[28292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Nov 21 20:56:29 mb sudo[28292]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 20:56:29 mb systemd[1]: Stopping UPnP Renderer front-end to MPD... Nov 21 20:56:29 mb volumio[1064]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Nov 21 20:56:30 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 21 20:56:32 mb volumio[1064]: info: Enabling MyMusic plugin upnp Nov 21 20:56:32 mb volumio[1064]: info: Enabling plugin upnp Nov 21 20:56:32 mb volumio[1064]: info: Loading plugin "upnp"... Nov 21 20:56:32 mb volumio[1064]: info: [1732222592074] Starting Upmpd Daemon Nov 21 20:56:32 mb volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 20:56:32 mb volumio[1064]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 20:56:32 mb volumio[1064]: { Error: listen EADDRINUSE :::6599 Nov 21 20:56:32 mb volumio[1064]: at Object._errnoException (util.js:1022:11) Nov 21 20:56:32 mb volumio[1064]: at _exceptionWithHostPort (util.js:1044:20) Nov 21 20:56:32 mb volumio[1064]: at Server.setupListenHandle [as _listen2] (net.js:1367:14) Nov 21 20:56:32 mb volumio[1064]: at listenInCluster (net.js:1408:12) Nov 21 20:56:32 mb volumio[1064]: at Server.listen (net.js:1492:7) Nov 21 20:56:32 mb volumio[1064]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Nov 21 20:56:32 mb volumio[1064]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:171:38) Nov 21 20:56:32 mb volumio[1064]: at Promise._successFn (/volumio/app/pluginmanager.js:1548:19) Nov 21 20:56:32 mb volumio[1064]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 21 20:56:32 mb volumio[1064]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Nov 21 20:56:32 mb volumio[1064]: at process._tickCallback (internal/process/next_tick.js:180:9) Nov 21 20:56:32 mb volumio[1064]: code: 'EADDRINUSE', Nov 21 20:56:32 mb volumio[1064]: errno: 'EADDRINUSE', Nov 21 20:56:32 mb volumio[1064]: syscall: 'listen', Nov 21 20:56:32 mb volumio[1064]: address: '::', Nov 21 20:56:32 mb volumio[1064]: port: 6599 } Nov 21 20:56:32 mb volumio[1064]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 20:56:32 mb sudo[28322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-21 20:55 Nov 21 20:56:32 mb sudo[28322]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="6d9442f761618503268d0366db805a252514dec1" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="projectsbus2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 13 17:22:02 CEST 2022" VOLUMIO_VERSION="1.079" VOLUMIO_HARDWARE="pi" VOLUMIO_VENDOR="Pro-Ject Audio Systems" VOLUMIO_MODEL="Stream Box S2 ultra" VOLUMIO_VENDOR_MODEL="Pro-Ject Stream Box S2 ultra" VOLUMIO_HASH="a5127ace113e6b9fcf2a1eaed5431093"