Apr 12 20:54:04 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 12 20:54:04 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 12 20:54:04 volumio python3[1556]: 2026-04-12 20:54:04,928 - INFO - watchdog_reset done. Apr 12 20:54:06 volumio volumio[1555]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 20:54:09 volumio python3[1556]: 2026-04-12 20:54:09,945 - INFO - watchdog_reset done. Apr 12 20:54:13 volumio volumio[1555]: info: CoreCommandRouter::volumioPlay Apr 12 20:54:13 volumio volumio[1555]: info: CoreStateMachine::play index undefined Apr 12 20:54:13 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 20:54:13 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:13 volumio volumio[1555]: info: CoreStateMachine::startPlaybackTimer Apr 12 20:54:13 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:13 volumio volumio[1555]: info: [1776020053079] ControllerSpotify::clearAddPlayTrack Apr 12 20:54:13 volumio volumio[1555]: info: Sending Spotify command with payload to local API: /player/play Apr 12 20:54:14 volumio volumio[1555]: info: CoreCommandRouter::volumioGetQueue Apr 12 20:54:14 volumio volumio[1555]: info: CoreStateMachine::getQueue Apr 12 20:54:14 volumio volumio[1555]: info: CorePlayQueue::getQueue Apr 12 20:54:14 volumio python3[1556]: 2026-04-12 20:54:14,961 - INFO - watchdog_reset done. Apr 12 20:54:17 volumio volumio[1555]: info: CoreCommandRouter::volumioRandom Apr 12 20:54:17 volumio volumio[1555]: info: CoreCommandRouter::writePlayerControls Apr 12 20:54:17 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:17 volumio volumio[1555]: info: CoreStateMachine::setRandom false Apr 12 20:54:17 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:17 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:17 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 20:54:17 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:17 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:19 volumio python3[1556]: 2026-04-12 20:54:19,978 - INFO - watchdog_reset done. Apr 12 20:54:24 volumio python3[1556]: 2026-04-12 20:54:24,994 - INFO - watchdog_reset done. Apr 12 20:54:27 volumio volumio[1555]: info: Preload queue cleared Apr 12 20:54:27 volumio volumio[1555]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 20:54:27 volumio volumio[1555]: info: CoreStateMachine::ClearQueue Apr 12 20:54:27 volumio volumio[1555]: info: CoreStateMachine::stop Apr 12 20:54:27 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 20:54:27 volumio volumio[1555]: info: CorePlayQueue::clearPlayQueue Apr 12 20:54:27 volumio volumio[1555]: info: CorePlayQueue::saveQueue Apr 12 20:54:27 volumio volumio[1555]: info: CoreCommandRouter::volumioPushQueue Apr 12 20:54:27 volumio volumio[1555]: info: CoreStateMachine::addQueueItems Apr 12 20:54:27 volumio volumio[1555]: info: CorePlayQueue::addQueueItems Apr 12 20:54:27 volumio volumio[1555]: info: Preload queue cleared Apr 12 20:54:27 volumio volumio[1555]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s24896 Apr 12 20:54:27 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 12 20:54:27 volumio volumio[1555]: info: CoreCommandRouter::volumioPushQueue Apr 12 20:54:27 volumio volumio[1555]: info: CorePlayQueue::saveQueue Apr 12 20:54:27 volumio volumio[1555]: info: CoreStateMachine::updateTrackBlock Apr 12 20:54:27 volumio volumio[1555]: info: CorePlayQueue::getTrackBlock Apr 12 20:54:27 volumio volumio[1555]: info: CoreCommandRouter::volumioPlay Apr 12 20:54:27 volumio volumio[1555]: info: CoreStateMachine::play index 0 Apr 12 20:54:27 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 20:54:27 volumio volumio[1555]: info: CoreStateMachine::stop Apr 12 20:54:27 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 20:54:27 volumio volumio[1555]: info: CoreStateMachine::play index undefined Apr 12 20:54:27 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 20:54:27 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:27 volumio volumio[1555]: info: CoreStateMachine::startPlaybackTimer Apr 12 20:54:27 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:27 volumio volumio[1555]: info: [1776020067511] ControllerWebradio::clearAddPlayTrack Apr 12 20:54:27 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 20:54:27 volumio volumio[1555]: info: sendMpdCommand stop took 2 milliseconds Apr 12 20:54:27 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 20:54:27 volumio volumio[1555]: info: sendMpdCommand clear took 0 milliseconds Apr 12 20:54:27 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand load "http://liveradio.swr.de/tn8jep3/swr3/" Apr 12 20:54:27 volumio volumio[1555]: info: Apr 12 20:54:27 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:27 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:27 volumio volumio[1555]: info: Apr 12 20:54:27 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:27 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:27 volumio volumio[1555]: info: Apr 12 20:54:27 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:27 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:27 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand add "http://liveradio.swr.de/tn8jep3/swr3/" Apr 12 20:54:27 volumio volumio[1555]: error: updateQueue error: null Apr 12 20:54:27 volumio volumio[1555]: error: updateQueue error: null Apr 12 20:54:27 volumio volumio[1555]: error: updateQueue error: null Apr 12 20:54:27 volumio volumio[1555]: info: ------------------------------ 391ms Apr 12 20:54:27 volumio volumio[1555]: info: ------------------------------ 390ms Apr 12 20:54:27 volumio volumio[1555]: info: ------------------------------ 390ms Apr 12 20:54:27 volumio volumio[1555]: info: Apr 12 20:54:27 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:27 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:27 volumio volumio[1555]: info: sendMpdCommand add "http://liveradio.swr.de/tn8jep3/swr3/" took 1 milliseconds Apr 12 20:54:27 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 20:54:27 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand play Apr 12 20:54:27 volumio volumio[1555]: info: Apr 12 20:54:27 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:27 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:27 volumio volumio[1555]: info: Apr 12 20:54:27 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:27 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:27 volumio volumio[1555]: info: ------------------------------ 4ms Apr 12 20:54:27 volumio volumio[1555]: info: sendMpdCommand play took 3 milliseconds Apr 12 20:54:27 volumio volumio[1555]: info: ------------------------------ 3ms Apr 12 20:54:27 volumio volumio[1555]: info: ------------------------------ 3ms Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand status took 8 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand status took 8 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand status took 7 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand status took 3 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand status took 1 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand status took 1 milliseconds Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:28 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:28 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus stop Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:28 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:28 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus play Apr 12 20:54:28 volumio volumio[1555]: info: Received an update from plugin. extracting info from payload Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:28 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:28 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus play Apr 12 20:54:28 volumio volumio[1555]: info: Received an update from plugin. extracting info from payload Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 23ms Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 36ms Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 36ms Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 24 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 23 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 23 milliseconds Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:28 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:28 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus play Apr 12 20:54:28 volumio volumio[1555]: info: Received an update from plugin. extracting info from payload Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:28 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:28 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus play Apr 12 20:54:28 volumio volumio[1555]: info: Received an update from plugin. extracting info from payload Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:28 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:28 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus play Apr 12 20:54:28 volumio volumio[1555]: info: Received an update from plugin. extracting info from payload Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 70ms Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 66ms Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 66ms Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: info: Preload queue cleared Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::ClearQueue Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::stop Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::stPlaybackTimer Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::updateTrackBlock Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrackBlock Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::serviceStop Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::serviceStop Apr 12 20:54:28 volumio volumio[1555]: info: [1776020068437] ControllerWebradio::stop Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::clearPlayQueue Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::saveQueue Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushQueue Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::addQueueItems Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::addQueueItems Apr 12 20:54:28 volumio volumio[1555]: info: Preload queue cleared Apr 12 20:54:28 volumio volumio[1555]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s24896 Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushQueue Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::saveQueue Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::updateTrackBlock Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrackBlock Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPlay Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::play index 0 Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::stop Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::play index undefined Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::startPlaybackTimer Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:28 volumio volumio[1555]: info: [1776020068448] ControllerWebradio::clearAddPlayTrack Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand stop took 15 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand stop took 4 milliseconds Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:28 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand status took 10 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand clear took 9 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand status took 9 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand status took 8 milliseconds Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand load "http://liveradio.swr.de/tn8jep3/swr3/" Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:28 volumio volumio[1555]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:28 volumio volumio[1555]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 21ms Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 22ms Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:28 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:28 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:28 volumio volumio[1555]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 12 20:54:28 volumio volumio[1555]: error: updateQueue error: null Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 16ms Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:28 volumio volumio[1555]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 26ms Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand add "http://liveradio.swr.de/tn8jep3/swr3/" Apr 12 20:54:28 volumio volumio[1555]: error: updateQueue error: null Apr 12 20:54:28 volumio volumio[1555]: error: updateQueue error: null Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 174ms Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 173ms Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand add "http://liveradio.swr.de/tn8jep3/swr3/" took 0 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand play Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:28 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:28 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:28 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand play took 1 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 1ms Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 1ms Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 1ms Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand status took 6 milliseconds Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand status took 6 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand status took 6 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand status took 0 milliseconds Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:28 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:28 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus stop Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 9ms Apr 12 20:54:28 volumio volumio[1555]: info: Apr 12 20:54:28 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand status took 6 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:28 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:28 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus play Apr 12 20:54:28 volumio volumio[1555]: info: Received an update from plugin. extracting info from payload Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:28 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:28 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus play Apr 12 20:54:28 volumio volumio[1555]: info: Received an update from plugin. extracting info from payload Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:28 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:28 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus play Apr 12 20:54:28 volumio volumio[1555]: info: Received an update from plugin. extracting info from payload Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 42ms Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 41ms Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 36ms Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand status took 30 milliseconds Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 30 milliseconds Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:28 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:28 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus play Apr 12 20:54:28 volumio volumio[1555]: info: Received an update from plugin. extracting info from payload Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 50ms Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 28 milliseconds Apr 12 20:54:28 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:28 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:28 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:28 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:28 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus play Apr 12 20:54:28 volumio volumio[1555]: info: Received an update from plugin. extracting info from payload Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:28 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:28 volumio volumio[1555]: info: ------------------------------ 71ms Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:28 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:29 volumio volumio[1555]: info: Apr 12 20:54:29 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:29 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:29 volumio volumio[1555]: info: Apr 12 20:54:29 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:29 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:29 volumio volumio[1555]: info: Apr 12 20:54:29 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:29 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:29 volumio volumio[1555]: info: Apr 12 20:54:29 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:29 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:29 volumio volumio[1555]: info: Apr 12 20:54:29 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:29 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:29 volumio volumio[1555]: info: Apr 12 20:54:29 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:29 volumio volumio[1555]: info: Apr 12 20:54:29 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:29 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:29 volumio volumio[1555]: info: Apr 12 20:54:29 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:29 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:29 volumio volumio[1555]: info: ------------------------------ 3ms Apr 12 20:54:29 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:29 volumio volumio[1555]: info: sendMpdCommand status took 2 milliseconds Apr 12 20:54:29 volumio volumio[1555]: info: ------------------------------ 2ms Apr 12 20:54:29 volumio volumio[1555]: info: sendMpdCommand status took 2 milliseconds Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:29 volumio volumio[1555]: info: Apr 12 20:54:29 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:29 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:29 volumio volumio[1555]: info: Apr 12 20:54:29 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:29 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:29 volumio volumio[1555]: info: Apr 12 20:54:29 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:29 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:29 volumio volumio[1555]: info: Apr 12 20:54:29 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:29 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:29 volumio volumio[1555]: info: ------------------------------ 4ms Apr 12 20:54:29 volumio volumio[1555]: info: sendMpdCommand status took 4 milliseconds Apr 12 20:54:29 volumio volumio[1555]: info: ------------------------------ 4ms Apr 12 20:54:29 volumio volumio[1555]: info: sendMpdCommand status took 4 milliseconds Apr 12 20:54:29 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 20:54:29 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 20:54:29 volumio volumio[1555]: info: ------------------------------ 3ms Apr 12 20:54:29 volumio volumio[1555]: info: sendMpdCommand status took 2 milliseconds Apr 12 20:54:29 volumio volumio[1555]: info: ------------------------------ 2ms Apr 12 20:54:29 volumio volumio[1555]: info: sendMpdCommand status took 2 milliseconds Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:29 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:29 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:29 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"My regards / Maisie Peters","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:29 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus play Apr 12 20:54:29 volumio volumio[1555]: info: Received an update from plugin. extracting info from payload Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 20:54:29 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:29 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:29 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:29 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"My regards / Maisie Peters","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:29 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus play Apr 12 20:54:29 volumio volumio[1555]: info: Received an update from plugin. extracting info from payload Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:29 volumio volumio[1555]: info: ------------------------------ 25ms Apr 12 20:54:29 volumio volumio[1555]: info: ------------------------------ 24ms Apr 12 20:54:29 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 19 milliseconds Apr 12 20:54:29 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 19 milliseconds Apr 12 20:54:29 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 19 milliseconds Apr 12 20:54:29 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 19 milliseconds Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:29 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:29 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:29 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:29 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"My regards / Maisie Peters","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:29 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus play Apr 12 20:54:29 volumio volumio[1555]: info: Received an update from plugin. extracting info from payload Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:29 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:29 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:29 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"My regards / Maisie Peters","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:29 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus play Apr 12 20:54:29 volumio volumio[1555]: info: Received an update from plugin. extracting info from payload Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:29 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:29 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:29 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"My regards / Maisie Peters","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:29 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus play Apr 12 20:54:29 volumio volumio[1555]: info: Received an update from plugin. extracting info from payload Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:29 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:29 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:29 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"My regards / Maisie Peters","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:29 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus play Apr 12 20:54:29 volumio volumio[1555]: info: Received an update from plugin. extracting info from payload Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:29 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:29 volumio volumio[1555]: info: ------------------------------ 63ms Apr 12 20:54:29 volumio volumio[1555]: info: ------------------------------ 63ms Apr 12 20:54:29 volumio volumio[1555]: info: ------------------------------ 62ms Apr 12 20:54:29 volumio volumio[1555]: info: ------------------------------ 62ms Apr 12 20:54:29 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:29 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:29 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:29 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:29 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:29 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:29 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:29 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:29 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:29 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:29 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:29 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 12 20:54:30 volumio python3[1556]: 2026-04-12 20:54:30,011 - INFO - watchdog_reset done. Apr 12 20:54:30 volumio volumio[1555]: info: VolumeController::SetAlsaVolume64 Apr 12 20:54:30 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:30 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 20:54:30 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:30 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Apr 12 20:54:30 volumio volumio[1555]: SPOTIFY: SPOTIFY VOLUME 73 Apr 12 20:54:30 volumio volumio[1555]: SPOTIFY: VOLUMIO VOLUME 64 Apr 12 20:54:30 volumio volumio[1555]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 12 20:54:30 volumio volumio[1555]: info: Setting Spotify Volume from Volumio: 64 Apr 12 20:54:31 volumio volumio[1555]: info: VolumeController::SetAlsaVolume59 Apr 12 20:54:31 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:31 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 20:54:31 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:31 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Apr 12 20:54:31 volumio volumio[1555]: SPOTIFY: SPOTIFY VOLUME 64 Apr 12 20:54:31 volumio volumio[1555]: SPOTIFY: VOLUMIO VOLUME 59 Apr 12 20:54:31 volumio volumio[1555]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 12 20:54:31 volumio volumio[1555]: info: Setting Spotify Volume from Volumio: 59 Apr 12 20:54:31 volumio volumio[1555]: info: VolumeController::SetAlsaVolume55 Apr 12 20:54:31 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:31 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 20:54:31 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:31 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 12 20:54:31 volumio volumio[1555]: SPOTIFY: SPOTIFY VOLUME 59 Apr 12 20:54:31 volumio volumio[1555]: SPOTIFY: VOLUMIO VOLUME 55 Apr 12 20:54:31 volumio volumio[1555]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 12 20:54:31 volumio volumio[1555]: info: Setting Spotify Volume from Volumio: 55 Apr 12 20:54:32 volumio volumio[1555]: info: VolumeController::SetAlsaVolume51 Apr 12 20:54:32 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:32 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 20:54:32 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:32 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 20:54:32 volumio volumio[1555]: SPOTIFY: SPOTIFY VOLUME 55 Apr 12 20:54:32 volumio volumio[1555]: SPOTIFY: VOLUMIO VOLUME 51 Apr 12 20:54:32 volumio volumio[1555]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 12 20:54:32 volumio volumio[1555]: info: Setting Spotify Volume from Volumio: 51 Apr 12 20:54:32 volumio volumio[1555]: info: VolumeController::SetAlsaVolume41 Apr 12 20:54:32 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:32 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 20:54:32 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:32 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 12 20:54:32 volumio volumio[1555]: SPOTIFY: SPOTIFY VOLUME 51 Apr 12 20:54:32 volumio volumio[1555]: SPOTIFY: VOLUMIO VOLUME 41 Apr 12 20:54:32 volumio volumio[1555]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 12 20:54:32 volumio volumio[1555]: info: Setting Spotify Volume from Volumio: 41 Apr 12 20:54:34 volumio volumio[1555]: SPOTIFY: SETTING SPOTIFY VOLUME 41 Apr 12 20:54:34 volumio volumio[1555]: info: Sending Spotify command with payload to local API: /player/volume Apr 12 20:54:35 volumio python3[1556]: 2026-04-12 20:54:35,028 - INFO - watchdog_reset done. Apr 12 20:54:40 volumio python3[1556]: 2026-04-12 20:54:40,044 - INFO - watchdog_reset done. Apr 12 20:54:42 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Apr 12 20:54:42 volumio volumio[1555]: info: In handleBrowseUri, curUri=spotify Apr 12 20:54:43 volumio volumio[1555]: info: New access token = BQC2xkNbhWG4kX96-qV-jsb7IEOa9IvgHdxG5iY6N2Diu2oLZbhWN9FR6oh1zvpO98ogVpU4rkltjKjdgpiu93biCE3QPDUCFK3DBLWelfce5o40mOO3pjAchvu4N1fKdSYzvFLCexyrmKKgDqMwIZR_CKiakg3MzDRtRkkUKO0Cy4oyVRU7k5Z1ZUXIqdHeM23cXVGm3gRzSVNgVMFhzY10tlq-FJ_dFNHvRyO8Y2OPNf7tX0pKIoop3Iq-EisHzmb0L4ESHT72eCCbuTH9W1kDxaheZytO6Ci6SQm7Uz3JgOtln9a2PoCKROb5r-c Apr 12 20:54:43 volumio volumio[1555]: info: New access token = BQB2w0As_YwF9Pln5X1b8J7XdIfNlh0qLrnrrMtWmcHWkKess_mG-vC7MpUn5qvuZU1EZIUovLnFEmsyNPtpoEnbuX1QHKpDqTfu9rBWrhISaCGwp9Ss8JvtGdJ3-YiKoknUP-NmxN4HbIaK8Pz9bjyYe2EUQyXZgT_9nrpLUCevUi9kVUajj5spwOyb40AcbLr3106uBhfkxj27T-hioQG7e8LotoiuRidrk4KNn0aS_UA6dwQyvTwnhQQQJ-kyKzt5JZhHgPeFjbmmZ1uLlPkb7fVXSJCZ1y_nGdofYOrMMJuRMcIZu0xLn-Ksmsw Apr 12 20:54:43 volumio volumio[1555]: info: New access token = BQBp2O_Nk4MPhnMu6eD0Ipc7brjdHmWltpzA2pG84pzfXSLYa4zqBlTUG5dYTQuuKijF6fwem-azVnWjuC6Xea8ZRGAMTyKxQpLyHe5iCQ_b73Tr49uixtj5dLm-cW5Hu2uKDghyLYNOThRVDawFLcY5EoYIfKA8AitB6pKN_yqJm_GpvdGAxPcO6JDyuvVzVrxay4fuClNmKCIyhi2Ff6RyFQf32_M9ZqJHO8L3ZjF_vlcc6c9RoZneeoR2WSsSdsr7J-_IIgZ7XJ4ulRMAt5VXZRWroigKQBAVpb57jrWXeClbUeIiUwQHB2PS_Sg Apr 12 20:54:43 volumio volumio[1555]: info: Preload queue cleared Apr 12 20:54:43 volumio volumio[1555]: info: Preload queue cleared Apr 12 20:54:43 volumio volumio[1555]: info: Preload queue cleared Apr 12 20:54:43 volumio volumio[1555]: info: Preload queue cleared Apr 12 20:54:45 volumio python3[1556]: 2026-04-12 20:54:45,061 - INFO - watchdog_reset done. Apr 12 20:54:45 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Apr 12 20:54:45 volumio volumio[1555]: info: In handleBrowseUri, curUri=spotify/playlists Apr 12 20:54:45 volumio volumio[1555]: info: Preload queue cleared Apr 12 20:54:47 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Apr 12 20:54:47 volumio volumio[1555]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:6HYYusUEX8ZVNzESrNIkQg Apr 12 20:54:50 volumio python3[1556]: 2026-04-12 20:54:50,078 - INFO - watchdog_reset done. Apr 12 20:54:51 volumio volumio[1555]: info: Apr 12 20:54:51 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:51 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:51 volumio volumio[1555]: info: Apr 12 20:54:51 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:51 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:51 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:51 volumio volumio[1555]: info: Apr 12 20:54:51 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:51 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:51 volumio volumio[1555]: info: Apr 12 20:54:51 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:51 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:51 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:51 volumio volumio[1555]: info: Apr 12 20:54:51 volumio volumio[1555]: ---------------------------- MPD announces system playlist update Apr 12 20:54:51 volumio volumio[1555]: info: Ignoring MPD Status Update Apr 12 20:54:51 volumio volumio[1555]: info: Apr 12 20:54:51 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:51 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:51 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:51 volumio volumio[1555]: info: ------------------------------ 2ms Apr 12 20:54:51 volumio volumio[1555]: info: sendMpdCommand status took 1 milliseconds Apr 12 20:54:51 volumio volumio[1555]: info: ------------------------------ 1ms Apr 12 20:54:51 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:51 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:51 volumio volumio[1555]: info: sendMpdCommand status took 2 milliseconds Apr 12 20:54:51 volumio volumio[1555]: info: ------------------------------ 1ms Apr 12 20:54:51 volumio volumio[1555]: info: sendMpdCommand status took 1 milliseconds Apr 12 20:54:51 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 12 20:54:51 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:51 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:51 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:51 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:51 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:51 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:51 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:51 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:51 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22856,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"POP mit Michael Reufsteck","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:51 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:51 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:51 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus play Apr 12 20:54:51 volumio volumio[1555]: info: Received an update from plugin. extracting info from payload Apr 12 20:54:51 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:51 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 20:54:51 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:51 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:51 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:51 volumio volumio[1555]: info: ------------------------------ 12ms Apr 12 20:54:51 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 12 20:54:51 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 12 20:54:51 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:51 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:51 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:51 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:51 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:51 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22856,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"POP mit Michael Reufsteck","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:51 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:51 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:51 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus play Apr 12 20:54:51 volumio volumio[1555]: info: Received an update from plugin. extracting info from payload Apr 12 20:54:51 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:51 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:51 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:51 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:51 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:51 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:51 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:51 volumio volumio[1555]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22856,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"POP mit Michael Reufsteck","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:51 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:51 volumio volumio[1555]: info: CoreStateMachine::syncState stateService play Apr 12 20:54:51 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus play Apr 12 20:54:51 volumio volumio[1555]: info: Received an update from plugin. extracting info from payload Apr 12 20:54:51 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:51 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:51 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:51 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:51 volumio volumio[1555]: info: ------------------------------ 33ms Apr 12 20:54:51 volumio volumio[1555]: info: ------------------------------ 32ms Apr 12 20:54:51 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 12 20:54:51 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 12 20:54:51 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 12 20:54:51 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 12 20:54:51 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 12 20:54:51 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 12 20:54:52 volumio volumio[1555]: info: Preload queue cleared Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:5H4A1lKv1k8tKMWrgGqZKc Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:2cOb9Tkac5NZdRTibJlUib Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:2iiI2mLkn018kHzMCREU73 Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:2K5WhDETxfxGpLUxd7sZFQ Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:7wjvoDduE0vZBX39sOQsAH Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:3jpV4zrvWmbJ5GNOizKztW Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:0q80Q9nHYtHH4lngvrKl6N Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:6CPAzenANbqPULCTsSoYzX Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:2FovXHrm50LazFViDIj9Sk Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:01uazE5Crk3GX4boeBYDz5 Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:7L9hYAGJnS8qjhxJCdNWtm Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:3eWwlBDhGG4v5bwUP5EXbq Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:0z5om9GwcrImJGR6cLwc2H Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:2NinJl3W4yeNmBnULKU7r9 Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:4FDH1VyFtlStsbfAtEDKtg Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:17QCvQmwD8LoIPDNaNE3Dq Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:6h9kGgPymptZNgHPiHqvRh Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:3lC9KCXXAEMIDzoUcYuJWQ Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:4Cw1dFbhnoCsQOSiyXDYh1 Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:7jpjLhVXEUIRD3Zk3Ze4Yu Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:2l8P8GL3Q1mGcmvdNSXFpA Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:3fC5K2R4o4CAdssR16MOlf Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:2EN14NKVPTEtngJWc7xxb6 Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:7B4K4K4dIAhhmaNcH4gb2C Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:5o6Y1cjXepMqJ8dIdRGpaW Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:5DFyNCtkU6sUE5r5BEl52Q Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:6zYNTmVWKirmziL3EkEbQz Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:1LYooZ9VvML83aT2KEqtWb Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:38K9Z42iFU1hkt6FG5igiS Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:6gppabWsRegQx1M1sAfRM4 Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:3H2tvgGjjx6Qff0tZcWKtK Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:2044TJs7V97EwvnWLldBJv Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:4fmRiZaBWt9BLWS80UbwHk Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:4ScMcTklLk7teycMJNEeI5 Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:0xv7uy4q6vqAWsj8UK1SVx Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:4bqU2weAO5THDKvyXQuQ3g Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:3gQGiLq8a0LK9sUbSQB1vY Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:0C9byNGLB0iKXfj4ZdjJSb Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:42P6AVwWS4KpC460fjddEo Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:1kwEiuIzNc3EEyvUlRhSpI Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:2wH2rat67i4MaB9BBMspsF Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:5Xak6kPzSqzZT1VRifZGQG Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:6QEFAnDHhvI90IpzwTAh12 Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:1RPkDryWTsou13XMip1Ea6 Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:5L3aZrBhMOKWQFeKHi6Qxa Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:7vxpax6pDIY1u2EsCjWTLP Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:5V8xKrsYQsLy1icImmSepH Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:1zqLceHfj8M2i3wd47OY5W Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:4IW4X2qV5unfGRiw7H2h70 Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:4x5Kye5xSEGnEj2vUR16dz Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:47pYZfWtKuISuLHXNO26pW Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:3RoYzSxbmu7KyGrKTFIzyT Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:2ujGA5Ch9XsGoamlg5JE3U Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:57wi46AMa6s9oSAeqeeSmW Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:3gDLQCr9iS4b1Fiu1nhCWJ Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:0SJ1cvNUuv68z4iQRh3PN0 Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:1J8QdEDPsi9N6cLR8BZkuH Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:2lnStzFf0tQcwCzKM9Q3c0 Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:4icR2zKRsz9OuVwxlRDwFq Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:4hIXYaaZ1sOUQ8NDHJG4Uj Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:5QmJm1CaI31DPVYQLFNTPl Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:2VTAyviblNqBGYu40UMaWe Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:5ls4uAHAh4SIme1b66CwvK Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:5OGMB4cnyr4IVLHd5qHRlL Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:789vdisq18URGB7BxN6W2h Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:0Y9UQLSk0bL0OlG0Jg2iZ6 Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:32JjNPnorqMSczDnluqzVW Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:0zxWPO7o8ykAkDGkUtfOhn Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:781Ngo2ixmAg5h37QIUnet Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:303AT0ygkr6dzAZAJUScWH Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:1yyMnbuD7QfQwib61pAIY8 Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:3p59rocdlTeDtZwMgXERV4 Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:70fSCYG8DC8UCRRDBl6fL5 Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:2q41IngpN1TO79Zb7QcOmA Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:7zftUUc5XzXx1e5otojxKg Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:3IU3nWyXJ3fyUdHhqDG3ln Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:6h9kGgPymptZNgHPiHqvRh Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:3lC9KCXXAEMIDzoUcYuJWQ Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:4Cw1dFbhnoCsQOSiyXDYh1 Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:7jpjLhVXEUIRD3Zk3Ze4Yu Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:2l8P8GL3Q1mGcmvdNSXFpA Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:3fC5K2R4o4CAdssR16MOlf Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:2EN14NKVPTEtngJWc7xxb6 Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:7B4K4K4dIAhhmaNcH4gb2C Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:5o6Y1cjXepMqJ8dIdRGpaW Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:5DFyNCtkU6sUE5r5BEl52Q Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:6zYNTmVWKirmziL3EkEbQz Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:1LYooZ9VvML83aT2KEqtWb Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:38K9Z42iFU1hkt6FG5igiS Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:6gppabWsRegQx1M1sAfRM4 Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:3H2tvgGjjx6Qff0tZcWKtK Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:2044TJs7V97EwvnWLldBJv Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:4fmRiZaBWt9BLWS80UbwHk Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:4ScMcTklLk7teycMJNEeI5 Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:0xv7uy4q6vqAWsj8UK1SVx Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:4bqU2weAO5THDKvyXQuQ3g Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:3gQGiLq8a0LK9sUbSQB1vY Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:0C9byNGLB0iKXfj4ZdjJSb Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:42P6AVwWS4KpC460fjddEo Apr 12 20:54:52 volumio volumio[1555]: info: Preloading song: spotify:track:1kwEiuIzNc3EEyvUlRhSpI Apr 12 20:54:52 volumio volumio[1555]: info: Exploding uri spotify:track:5H4A1lKv1k8tKMWrgGqZKc in service spop Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:5H4A1lKv1k8tKMWrgGqZKc Apr 12 20:54:52 volumio volumio[1555]: info: Exploding uri spotify:track:2cOb9Tkac5NZdRTibJlUib in service spop Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:2cOb9Tkac5NZdRTibJlUib Apr 12 20:54:52 volumio volumio[1555]: info: Exploding uri spotify:track:2iiI2mLkn018kHzMCREU73 in service spop Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:2iiI2mLkn018kHzMCREU73 Apr 12 20:54:52 volumio volumio[1555]: info: Exploding uri spotify:track:2K5WhDETxfxGpLUxd7sZFQ in service spop Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:2K5WhDETxfxGpLUxd7sZFQ Apr 12 20:54:52 volumio volumio[1555]: info: Exploding uri spotify:track:7wjvoDduE0vZBX39sOQsAH in service spop Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:7wjvoDduE0vZBX39sOQsAH Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5H4A1lKv1k8tKMWrgGqZKc","service":"spop","name":"528 Hz Restore & Heal","artist":"Ashtar Being","album":"Peace","type":"song","duration":126,"albumart":"https://i.scdn.co/image/ab67616d0000b273da44bf87d21e6a65c4083a8b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:52 volumio volumio[1555]: info: Exploding uri spotify:track:3jpV4zrvWmbJ5GNOizKztW in service spop Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:3jpV4zrvWmbJ5GNOizKztW Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2cOb9Tkac5NZdRTibJlUib","service":"spop","name":"528 Hz Calm Breath","artist":"Ashtar Being","album":"Peace","type":"song","duration":124,"albumart":"https://i.scdn.co/image/ab67616d0000b273da44bf87d21e6a65c4083a8b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:52 volumio volumio[1555]: info: Exploding uri spotify:track:0q80Q9nHYtHH4lngvrKl6N in service spop Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:0q80Q9nHYtHH4lngvrKl6N Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2iiI2mLkn018kHzMCREU73","service":"spop","name":"528 Hz Forever Free","artist":"Ashtar Being","album":"Peace","type":"song","duration":122,"albumart":"https://i.scdn.co/image/ab67616d0000b273da44bf87d21e6a65c4083a8b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:52 volumio volumio[1555]: info: Exploding uri spotify:track:6CPAzenANbqPULCTsSoYzX in service spop Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:6CPAzenANbqPULCTsSoYzX Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2K5WhDETxfxGpLUxd7sZFQ","service":"spop","name":"528 Hz Manifestation","artist":"Ancient Atlantis","album":"Abundance","type":"song","duration":123,"albumart":"https://i.scdn.co/image/ab67616d0000b273994cf8014e4a3530f9f7bcfa","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:52 volumio volumio[1555]: info: Exploding uri spotify:track:2FovXHrm50LazFViDIj9Sk in service spop Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:2FovXHrm50LazFViDIj9Sk Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7wjvoDduE0vZBX39sOQsAH","service":"spop","name":"528 Hz Love Frequency","artist":"Ancient Atlantis","album":"Abundance","type":"song","duration":124,"albumart":"https://i.scdn.co/image/ab67616d0000b273994cf8014e4a3530f9f7bcfa","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:52 volumio volumio[1555]: info: Exploding uri spotify:track:01uazE5Crk3GX4boeBYDz5 in service spop Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:01uazE5Crk3GX4boeBYDz5 Apr 12 20:54:52 volumio volumio[1555]: info: Exploding uri spotify:track:7L9hYAGJnS8qjhxJCdNWtm in service spop Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:7L9hYAGJnS8qjhxJCdNWtm Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3jpV4zrvWmbJ5GNOizKztW","service":"spop","name":"Embracing Abundance","artist":"Auric Sensation","album":"Allowing Change","type":"song","duration":124,"albumart":"https://i.scdn.co/image/ab67616d0000b2732f41683798438b3b8ab9d2ee","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0q80Q9nHYtHH4lngvrKl6N","service":"spop","name":"Ancient Sound","artist":"Auric Sensation","album":"Allowing Change","type":"song","duration":122,"albumart":"https://i.scdn.co/image/ab67616d0000b2732f41683798438b3b8ab9d2ee","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:52 volumio volumio[1555]: info: Exploding uri spotify:track:3eWwlBDhGG4v5bwUP5EXbq in service spop Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:3eWwlBDhGG4v5bwUP5EXbq Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6CPAzenANbqPULCTsSoYzX","service":"spop","name":"528 Hz Freedom Within","artist":"Ashtar Being","album":"Peace","type":"song","duration":123,"albumart":"https://i.scdn.co/image/ab67616d0000b273da44bf87d21e6a65c4083a8b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:52 volumio volumio[1555]: info: Exploding uri spotify:track:0z5om9GwcrImJGR6cLwc2H in service spop Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:0z5om9GwcrImJGR6cLwc2H Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2FovXHrm50LazFViDIj9Sk","service":"spop","name":"528 Hz Peace & Prosperity","artist":"Ashtar Being","album":"Peace","type":"song","duration":129,"albumart":"https://i.scdn.co/image/ab67616d0000b273da44bf87d21e6a65c4083a8b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:52 volumio volumio[1555]: info: Exploding uri spotify:track:2NinJl3W4yeNmBnULKU7r9 in service spop Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:2NinJl3W4yeNmBnULKU7r9 Apr 12 20:54:52 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7L9hYAGJnS8qjhxJCdNWtm","service":"spop","name":"528 Hz Miraculous Manifestation","artist":"Ashtar Being","album":"Peace","type":"song","duration":123,"albumart":"https://i.scdn.co/image/ab67616d0000b273da44bf87d21e6a65c4083a8b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:01uazE5Crk3GX4boeBYDz5","service":"spop","name":"528 Hz Open and Receptive","artist":"Ashtar Being","album":"Peace","type":"song","duration":122,"albumart":"https://i.scdn.co/image/ab67616d0000b273da44bf87d21e6a65c4083a8b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: info: Exploding uri spotify:track:4FDH1VyFtlStsbfAtEDKtg in service spop Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:4FDH1VyFtlStsbfAtEDKtg Apr 12 20:54:53 volumio volumio[1555]: info: Exploding uri spotify:track:17QCvQmwD8LoIPDNaNE3Dq in service spop Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:17QCvQmwD8LoIPDNaNE3Dq Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3eWwlBDhGG4v5bwUP5EXbq","service":"spop","name":"528 Hz Releasing Mental Blocks","artist":"Ancient Atlantis","album":"Free Spirit","type":"song","duration":124,"albumart":"https://i.scdn.co/image/ab67616d0000b273717817f6fee6b1e70e1e796d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: info: Exploding uri spotify:track:6h9kGgPymptZNgHPiHqvRh in service spop Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:6h9kGgPymptZNgHPiHqvRh Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0z5om9GwcrImJGR6cLwc2H","service":"spop","name":"528 Hz Healing the Past","artist":"Ancient Atlantis","album":"Free Spirit","type":"song","duration":124,"albumart":"https://i.scdn.co/image/ab67616d0000b273717817f6fee6b1e70e1e796d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: info: Exploding uri spotify:track:3lC9KCXXAEMIDzoUcYuJWQ in service spop Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:3lC9KCXXAEMIDzoUcYuJWQ Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2NinJl3W4yeNmBnULKU7r9","service":"spop","name":"528 Hz Loving Feeling","artist":"Ancient Atlantis","album":"Free Spirit","type":"song","duration":122,"albumart":"https://i.scdn.co/image/ab67616d0000b273717817f6fee6b1e70e1e796d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4FDH1VyFtlStsbfAtEDKtg","service":"spop","name":"Warmth & Comfort","artist":"Auric Sensation","album":"Sacred Signs","type":"song","duration":124,"albumart":"https://i.scdn.co/image/ab67616d0000b273943dcdceb79fe841ae6ddc78","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: info: Exploding uri spotify:track:4Cw1dFbhnoCsQOSiyXDYh1 in service spop Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:4Cw1dFbhnoCsQOSiyXDYh1 Apr 12 20:54:53 volumio volumio[1555]: info: Exploding uri spotify:track:7jpjLhVXEUIRD3Zk3Ze4Yu in service spop Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:7jpjLhVXEUIRD3Zk3Ze4Yu Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6h9kGgPymptZNgHPiHqvRh","service":"spop","name":"Fade Into Calm","artist":"Light Vessel","album":"Fade Into Calm","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b273e78e067646fa1898c8b59dc1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: info: Exploding uri spotify:track:2l8P8GL3Q1mGcmvdNSXFpA in service spop Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:2l8P8GL3Q1mGcmvdNSXFpA Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:17QCvQmwD8LoIPDNaNE3Dq","service":"spop","name":"Sacred Signs","artist":"Auric Sensation","album":"Sacred Signs","type":"song","duration":124,"albumart":"https://i.scdn.co/image/ab67616d0000b273943dcdceb79fe841ae6ddc78","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: info: Exploding uri spotify:track:3fC5K2R4o4CAdssR16MOlf in service spop Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:3fC5K2R4o4CAdssR16MOlf Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3lC9KCXXAEMIDzoUcYuJWQ","service":"spop","name":"528 Hz Light Ascending","artist":"Golden Age Inc","album":"Inner Horizon","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b2732485eb5e76744924e0993a7b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: info: Exploding uri spotify:track:2EN14NKVPTEtngJWc7xxb6 in service spop Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:2EN14NKVPTEtngJWc7xxb6 Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4Cw1dFbhnoCsQOSiyXDYh1","service":"spop","name":"528 Hz Panacea","artist":"Portal of Light","album":"Panacea","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b273c9e86ff1337c5cbee2f1a2db","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7jpjLhVXEUIRD3Zk3Ze4Yu","service":"spop","name":"Ember Cloud","artist":"Andromedan Light","album":"Ember Cloud","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b273b6d702da671e52440b980b0c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: info: Exploding uri spotify:track:7B4K4K4dIAhhmaNcH4gb2C in service spop Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:7B4K4K4dIAhhmaNcH4gb2C Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2l8P8GL3Q1mGcmvdNSXFpA","service":"spop","name":"528 Hz Melt Away","artist":"Essassani Light","album":"Melt Away","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b273bb9c59ed49fea95a8dc31039","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: info: Exploding uri spotify:track:5o6Y1cjXepMqJ8dIdRGpaW in service spop Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:5o6Y1cjXepMqJ8dIdRGpaW Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3fC5K2R4o4CAdssR16MOlf","service":"spop","name":"528 Hz Irisbloom","artist":"Angelic Council","album":"Irisbloom","type":"song","duration":66,"albumart":"https://i.scdn.co/image/ab67616d0000b273a37d769cd5c6ab8a6fdd22ae","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: info: Exploding uri spotify:track:5DFyNCtkU6sUE5r5BEl52Q in service spop Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:5DFyNCtkU6sUE5r5BEl52Q Apr 12 20:54:53 volumio volumio[1555]: info: Exploding uri spotify:track:6zYNTmVWKirmziL3EkEbQz in service spop Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:6zYNTmVWKirmziL3EkEbQz Apr 12 20:54:53 volumio volumio[1555]: info: Exploding uri spotify:track:1LYooZ9VvML83aT2KEqtWb in service spop Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:1LYooZ9VvML83aT2KEqtWb Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2EN14NKVPTEtngJWc7xxb6","service":"spop","name":"528 Hz Vellicate","artist":"Portal of Light","album":"Panacea","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b273c9e86ff1337c5cbee2f1a2db","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7B4K4K4dIAhhmaNcH4gb2C","service":"spop","name":"528 Hz Harmonia","artist":"Starlight Aura","album":"Genesis","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b2733b014d9a5f1c9cbd5b4b5af6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5o6Y1cjXepMqJ8dIdRGpaW","service":"spop","name":"528 Hz Healing Field","artist":"Aura Harmony","album":"528 Hz Healing Field","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b273094c47a2fc5a08f15d74ee73","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: info: Exploding uri spotify:track:38K9Z42iFU1hkt6FG5igiS in service spop Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:38K9Z42iFU1hkt6FG5igiS Apr 12 20:54:53 volumio volumio[1555]: info: Exploding uri spotify:track:6gppabWsRegQx1M1sAfRM4 in service spop Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:6gppabWsRegQx1M1sAfRM4 Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5DFyNCtkU6sUE5r5BEl52Q","service":"spop","name":"528 Hz Starweave","artist":"Sacred Light Vision","album":"Jadepeace","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b2734343a7b5349881c750a23f57","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: info: Exploding uri spotify:track:3H2tvgGjjx6Qff0tZcWKtK in service spop Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:3H2tvgGjjx6Qff0tZcWKtK Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6zYNTmVWKirmziL3EkEbQz","service":"spop","name":"528 Hz Heartglow","artist":"Arcturian Light","album":"Heartglow","type":"song","duration":67,"albumart":"https://i.scdn.co/image/ab67616d0000b2735f4535222d630b969d546e24","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: info: Exploding uri spotify:track:2044TJs7V97EwvnWLldBJv in service spop Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:2044TJs7V97EwvnWLldBJv Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1LYooZ9VvML83aT2KEqtWb","service":"spop","name":"528 Hz Cosmic Dust","artist":"Astro Energy","album":"Cosmic Dust","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b2737d65faed6278af5b6e0275ad","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:38K9Z42iFU1hkt6FG5igiS","service":"spop","name":"528 Hz Heavenly","artist":"Ashtar Being","album":"Dreamkeeper","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b273eb41891a633ee319a6e1749c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: info: Exploding uri spotify:track:4fmRiZaBWt9BLWS80UbwHk in service spop Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:4fmRiZaBWt9BLWS80UbwHk Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6gppabWsRegQx1M1sAfRM4","service":"spop","name":"528 Hz Skybound","artist":"Ashtar Being","album":"Dreamkeeper","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b273eb41891a633ee319a6e1749c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:53 volumio volumio[1555]: info: Exploding uri spotify:track:4ScMcTklLk7teycMJNEeI5 in service spop Apr 12 20:54:53 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:4ScMcTklLk7teycMJNEeI5 Apr 12 20:54:54 volumio volumio[1555]: info: Exploding uri spotify:track:0xv7uy4q6vqAWsj8UK1SVx in service spop Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:0xv7uy4q6vqAWsj8UK1SVx Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3H2tvgGjjx6Qff0tZcWKtK","service":"spop","name":"528 Hz Dreamkeeper","artist":"Ashtar Being","album":"Dreamkeeper","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b273eb41891a633ee319a6e1749c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:54 volumio volumio[1555]: info: Exploding uri spotify:track:4bqU2weAO5THDKvyXQuQ3g in service spop Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:4bqU2weAO5THDKvyXQuQ3g Apr 12 20:54:54 volumio volumio[1555]: info: Exploding uri spotify:track:3gQGiLq8a0LK9sUbSQB1vY in service spop Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:3gQGiLq8a0LK9sUbSQB1vY Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2044TJs7V97EwvnWLldBJv","service":"spop","name":"528 Hz Heart of Stillness","artist":"Ashtar Being","album":"Dreamkeeper","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b273eb41891a633ee319a6e1749c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4ScMcTklLk7teycMJNEeI5","service":"spop","name":"528 Hz Calm Within You","artist":"Ashtar Being","album":"Dreamkeeper","type":"song","duration":65,"albumart":"https://i.scdn.co/image/ab67616d0000b273eb41891a633ee319a6e1749c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:54 volumio volumio[1555]: info: Exploding uri spotify:track:0C9byNGLB0iKXfj4ZdjJSb in service spop Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:0C9byNGLB0iKXfj4ZdjJSb Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4fmRiZaBWt9BLWS80UbwHk","service":"spop","name":"528 Hz Return to Light","artist":"Ashtar Being","album":"Dreamkeeper","type":"song","duration":64,"albumart":"https://i.scdn.co/image/ab67616d0000b273eb41891a633ee319a6e1749c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:54 volumio volumio[1555]: info: Exploding uri spotify:track:42P6AVwWS4KpC460fjddEo in service spop Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:42P6AVwWS4KpC460fjddEo Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0xv7uy4q6vqAWsj8UK1SVx","service":"spop","name":"528 Hz Earth & Ether","artist":"Ashtar Being","album":"Dreamkeeper","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b273eb41891a633ee319a6e1749c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4bqU2weAO5THDKvyXQuQ3g","service":"spop","name":"528 Hz Solarian","artist":"Starlight Aura","album":"Solarian","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b273988550701a0d9eedc4da2cf0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:54 volumio volumio[1555]: info: Exploding uri spotify:track:1kwEiuIzNc3EEyvUlRhSpI in service spop Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:1kwEiuIzNc3EEyvUlRhSpI Apr 12 20:54:54 volumio volumio[1555]: info: Exploding uri spotify:track:2wH2rat67i4MaB9BBMspsF in service spop Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:2wH2rat67i4MaB9BBMspsF Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3gQGiLq8a0LK9sUbSQB1vY","service":"spop","name":"Becoming Still","artist":"Light Vessel","album":"Fade Into Calm","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b273e78e067646fa1898c8b59dc1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:54 volumio volumio[1555]: info: Exploding uri spotify:track:5Xak6kPzSqzZT1VRifZGQG in service spop Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:5Xak6kPzSqzZT1VRifZGQG Apr 12 20:54:54 volumio volumio[1555]: info: Exploding uri spotify:track:6QEFAnDHhvI90IpzwTAh12 in service spop Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:6QEFAnDHhvI90IpzwTAh12 Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:42P6AVwWS4KpC460fjddEo","service":"spop","name":"528 Hz Omenia","artist":"Portal of Light","album":"Vibrancea","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b273820faa1fef1618d6d64389ba","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1kwEiuIzNc3EEyvUlRhSpI","service":"spop","name":"528 Hz Emberhaze","artist":"Andromedan Light","album":"Opalspark","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b273bd7bda45ce9b1e6d93077d11","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0C9byNGLB0iKXfj4ZdjJSb","service":"spop","name":"528 Hz Inner Horizon","artist":"Golden Age Inc","album":"Inner Horizon","type":"song","duration":64,"albumart":"https://i.scdn.co/image/ab67616d0000b2732485eb5e76744924e0993a7b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:54 volumio volumio[1555]: info: Exploding uri spotify:track:1RPkDryWTsou13XMip1Ea6 in service spop Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:1RPkDryWTsou13XMip1Ea6 Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2wH2rat67i4MaB9BBMspsF","service":"spop","name":"528 Hz Now & Forever","artist":"Essassani Light","album":"Melt Away","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b273bb9c59ed49fea95a8dc31039","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:54 volumio volumio[1555]: info: Exploding uri spotify:track:5L3aZrBhMOKWQFeKHi6Qxa in service spop Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:5L3aZrBhMOKWQFeKHi6Qxa Apr 12 20:54:54 volumio volumio[1555]: info: Exploding uri spotify:track:7vxpax6pDIY1u2EsCjWTLP in service spop Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:7vxpax6pDIY1u2EsCjWTLP Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5Xak6kPzSqzZT1VRifZGQG","service":"spop","name":"528 Hz Nighthush","artist":"Angelic Council","album":"Irisbloom","type":"song","duration":64,"albumart":"https://i.scdn.co/image/ab67616d0000b273a37d769cd5c6ab8a6fdd22ae","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6QEFAnDHhvI90IpzwTAh12","service":"spop","name":"528 Hz Vibrancea","artist":"Portal of Light","album":"Vibrancea","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b273820faa1fef1618d6d64389ba","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:54 volumio volumio[1555]: info: Exploding uri spotify:track:5V8xKrsYQsLy1icImmSepH in service spop Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:5V8xKrsYQsLy1icImmSepH Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1RPkDryWTsou13XMip1Ea6","service":"spop","name":"528 Hz Genesis","artist":"Starlight Aura","album":"Genesis","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b2733b014d9a5f1c9cbd5b4b5af6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:54 volumio volumio[1555]: info: Exploding uri spotify:track:1zqLceHfj8M2i3wd47OY5W in service spop Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:1zqLceHfj8M2i3wd47OY5W Apr 12 20:54:54 volumio volumio[1555]: info: Exploding uri spotify:track:4IW4X2qV5unfGRiw7H2h70 in service spop Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:4IW4X2qV5unfGRiw7H2h70 Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5L3aZrBhMOKWQFeKHi6Qxa","service":"spop","name":"528 Hz Cloudrest","artist":"Aura Harmony","album":"Echoform","type":"song","duration":64,"albumart":"https://i.scdn.co/image/ab67616d0000b273753706c9d21512de3e4a4c10","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:54 volumio volumio[1555]: info: Exploding uri spotify:track:4x5Kye5xSEGnEj2vUR16dz in service spop Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:4x5Kye5xSEGnEj2vUR16dz Apr 12 20:54:54 volumio volumio[1555]: info: Exploding uri spotify:track:47pYZfWtKuISuLHXNO26pW in service spop Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:47pYZfWtKuISuLHXNO26pW Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5V8xKrsYQsLy1icImmSepH","service":"spop","name":"Moonbow","artist":"Arcturian Light","album":"Moonbow","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b273289ff0fb05389f8087569459","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7vxpax6pDIY1u2EsCjWTLP","service":"spop","name":"528 Hz Jadepeace","artist":"Sacred Light Vision","album":"Jadepeace","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b2734343a7b5349881c750a23f57","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:54 volumio volumio[1555]: info: Exploding uri spotify:track:3RoYzSxbmu7KyGrKTFIzyT in service spop Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:3RoYzSxbmu7KyGrKTFIzyT Apr 12 20:54:54 volumio volumio[1555]: info: Exploding uri spotify:track:2ujGA5Ch9XsGoamlg5JE3U in service spop Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:2ujGA5Ch9XsGoamlg5JE3U Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4x5Kye5xSEGnEj2vUR16dz","service":"spop","name":"528 Hz Interlumen","artist":"Ashtar Being","album":"Novaflare","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b273abfa05fb273f27320faa81a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:54 volumio volumio[1555]: info: Exploding uri spotify:track:57wi46AMa6s9oSAeqeeSmW in service spop Apr 12 20:54:54 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:57wi46AMa6s9oSAeqeeSmW Apr 12 20:54:55 volumio volumio[1555]: info: Exploding uri spotify:track:3gDLQCr9iS4b1Fiu1nhCWJ in service spop Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:3gDLQCr9iS4b1Fiu1nhCWJ Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:47pYZfWtKuISuLHXNO26pW","service":"spop","name":"528 Hz Astralume","artist":"Ashtar Being","album":"Astralume","type":"song","duration":64,"albumart":"https://i.scdn.co/image/ab67616d0000b273aad787d75cf4dd406527524b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3RoYzSxbmu7KyGrKTFIzyT","service":"spop","name":"528 Hz Bloomfield","artist":"Ashtar Being","album":"Bloomfield","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a4bff77f5205471b581c385","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio volumio[1555]: info: Exploding uri spotify:track:0SJ1cvNUuv68z4iQRh3PN0 in service spop Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:0SJ1cvNUuv68z4iQRh3PN0 Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2ujGA5Ch9XsGoamlg5JE3U","service":"spop","name":"528 Hz Mirasense","artist":"Ashtar Being","album":"Featherspark","type":"song","duration":64,"albumart":"https://i.scdn.co/image/ab67616d0000b273358609ff550b7c4802b30a99","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio python3[1556]: 2026-04-12 20:54:55,094 - INFO - watchdog_reset done. Apr 12 20:54:55 volumio volumio[1555]: info: Exploding uri spotify:track:1J8QdEDPsi9N6cLR8BZkuH in service spop Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:1J8QdEDPsi9N6cLR8BZkuH Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:57wi46AMa6s9oSAeqeeSmW","service":"spop","name":"528 Hz Novaflare","artist":"Ashtar Being","album":"Novaflare","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b273abfa05fb273f27320faa81a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio volumio[1555]: info: Exploding uri spotify:track:2lnStzFf0tQcwCzKM9Q3c0 in service spop Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:2lnStzFf0tQcwCzKM9Q3c0 Apr 12 20:54:55 volumio volumio[1555]: info: Exploding uri spotify:track:4icR2zKRsz9OuVwxlRDwFq in service spop Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:4icR2zKRsz9OuVwxlRDwFq Apr 12 20:54:55 volumio volumio[1555]: info: Exploding uri spotify:track:4hIXYaaZ1sOUQ8NDHJG4Uj in service spop Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:4hIXYaaZ1sOUQ8NDHJG4Uj Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0SJ1cvNUuv68z4iQRh3PN0","service":"spop","name":"528 Hz Respiria","artist":"Starlight Aura","album":"Solarian","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b273988550701a0d9eedc4da2cf0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1J8QdEDPsi9N6cLR8BZkuH","service":"spop","name":"528 Hz Dreamwind","artist":"Light Vessel","album":"528 Hz Dreamwind","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b273ead7252b192a42a4678d2b88","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio volumio[1555]: info: Exploding uri spotify:track:5QmJm1CaI31DPVYQLFNTPl in service spop Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:5QmJm1CaI31DPVYQLFNTPl Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3gDLQCr9iS4b1Fiu1nhCWJ","service":"spop","name":"528 Hz Pure Being","artist":"Ashtar Being","album":"Dreamkeeper","type":"song","duration":64,"albumart":"https://i.scdn.co/image/ab67616d0000b273eb41891a633ee319a6e1749c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2lnStzFf0tQcwCzKM9Q3c0","service":"spop","name":"528 Hz Weightless Calm","artist":"Golden Age Inc","album":"528 Hz Weightless Calm","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b27305b0755bc4a68ed7831e5dab","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio volumio[1555]: info: Exploding uri spotify:track:2VTAyviblNqBGYu40UMaWe in service spop Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:2VTAyviblNqBGYu40UMaWe Apr 12 20:54:55 volumio volumio[1555]: info: Exploding uri spotify:track:5ls4uAHAh4SIme1b66CwvK in service spop Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:5ls4uAHAh4SIme1b66CwvK Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4icR2zKRsz9OuVwxlRDwFq","service":"spop","name":"528 Hz Golden Aura","artist":"Portal of Light","album":"Golden Aura","type":"song","duration":66,"albumart":"https://i.scdn.co/image/ab67616d0000b27321777a8a1bada63c2709fa54","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio volumio[1555]: info: Exploding uri spotify:track:5OGMB4cnyr4IVLHd5qHRlL in service spop Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:5OGMB4cnyr4IVLHd5qHRlL Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4hIXYaaZ1sOUQ8NDHJG4Uj","service":"spop","name":"528 Hz Everlasting","artist":"Andromedan Light","album":"528 Hz Everlasting","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b2739b81b9841c727377be3882a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio volumio[1555]: info: Exploding uri spotify:track:789vdisq18URGB7BxN6W2h in service spop Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:789vdisq18URGB7BxN6W2h Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5QmJm1CaI31DPVYQLFNTPl","service":"spop","name":"528 Hz Peacefield","artist":"Essassani Light","album":"528 Hz Peacefield","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b2735b950d45a46355ebe0083354","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio volumio[1555]: info: Exploding uri spotify:track:0Y9UQLSk0bL0OlG0Jg2iZ6 in service spop Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:0Y9UQLSk0bL0OlG0Jg2iZ6 Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2VTAyviblNqBGYu40UMaWe","service":"spop","name":"528 Hz Stillpoint","artist":"Angelic Council","album":"528 Hz Stillpoint","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b27387cd024f1a4c9297512c6526","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio volumio[1555]: info: Exploding uri spotify:track:32JjNPnorqMSczDnluqzVW in service spop Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:32JjNPnorqMSczDnluqzVW Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5ls4uAHAh4SIme1b66CwvK","service":"spop","name":"528 Hz Wings of Grace","artist":"Portal of Light","album":"Golden Aura","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b27321777a8a1bada63c2709fa54","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio volumio[1555]: info: Exploding uri spotify:track:0zxWPO7o8ykAkDGkUtfOhn in service spop Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:0zxWPO7o8ykAkDGkUtfOhn Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5OGMB4cnyr4IVLHd5qHRlL","service":"spop","name":"528 Hz Soft Ascension","artist":"Starlight Aura","album":"Soft Ascension","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b2739fed98b58cef781a517d9778","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio volumio[1555]: info: Exploding uri spotify:track:781Ngo2ixmAg5h37QIUnet in service spop Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:781Ngo2ixmAg5h37QIUnet Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:789vdisq18URGB7BxN6W2h","service":"spop","name":"528 Hz Always Held","artist":"Aura Harmony","album":"528 Hz Always Held","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b273d84c107a9d995b05195be487","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio volumio[1555]: info: Exploding uri spotify:track:303AT0ygkr6dzAZAJUScWH in service spop Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:303AT0ygkr6dzAZAJUScWH Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0Y9UQLSk0bL0OlG0Jg2iZ6","service":"spop","name":"528 Hz Open to Receiving","artist":"Sacred Light Vision","album":"528 Hz Open to Receiving","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b273de97feaa04f23ad3a7bfea84","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio volumio[1555]: info: Exploding uri spotify:track:1yyMnbuD7QfQwib61pAIY8 in service spop Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:1yyMnbuD7QfQwib61pAIY8 Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:32JjNPnorqMSczDnluqzVW","service":"spop","name":"528 Hz Deep Ease","artist":"Arcturian Light","album":"528 Hz Deep Ease","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b273f88e1fb831d0d605709e3da9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio volumio[1555]: info: Exploding uri spotify:track:3p59rocdlTeDtZwMgXERV4 in service spop Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:3p59rocdlTeDtZwMgXERV4 Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0zxWPO7o8ykAkDGkUtfOhn","service":"spop","name":"528 Hz Clear Presence","artist":"Astro Energy","album":"528 Hz Clear Presence","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b273016c266e5df7b60e29c74f86","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1zqLceHfj8M2i3wd47OY5W","service":"spop","name":"528 Hz Return to Center","artist":"Astro Energy","album":"Cosmic Dust","type":"song","duration":64,"albumart":"https://i.scdn.co/image/ab67616d0000b2737d65faed6278af5b6e0275ad","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:781Ngo2ixmAg5h37QIUnet","service":"spop","name":"528 Hz Terravast","artist":"Ashtar Being","album":"Astralume","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b273aad787d75cf4dd406527524b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio volumio[1555]: info: Exploding uri spotify:track:70fSCYG8DC8UCRRDBl6fL5 in service spop Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:70fSCYG8DC8UCRRDBl6fL5 Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:303AT0ygkr6dzAZAJUScWH","service":"spop","name":"528 Hz Lighthaven","artist":"Ashtar Being","album":"Bloomfield","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a4bff77f5205471b581c385","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:55 volumio volumio[1555]: info: Exploding uri spotify:track:2q41IngpN1TO79Zb7QcOmA in service spop Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:2q41IngpN1TO79Zb7QcOmA Apr 12 20:54:55 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4IW4X2qV5unfGRiw7H2h70","service":"spop","name":"528 Hz Featherspark","artist":"Ashtar Being","album":"Featherspark","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b273358609ff550b7c4802b30a99","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:56 volumio volumio[1555]: info: Exploding uri spotify:track:7zftUUc5XzXx1e5otojxKg in service spop Apr 12 20:54:56 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:7zftUUc5XzXx1e5otojxKg Apr 12 20:54:56 volumio volumio[1555]: info: Exploding uri spotify:track:3IU3nWyXJ3fyUdHhqDG3ln in service spop Apr 12 20:54:56 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:track:3IU3nWyXJ3fyUdHhqDG3ln Apr 12 20:54:56 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3p59rocdlTeDtZwMgXERV4","service":"spop","name":"528 Hz Fjordcalm","artist":"Ashtar Being","album":"Novaflare","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b273abfa05fb273f27320faa81a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:56 volumio volumio[1555]: info: Using cached record of: spotify:track:6h9kGgPymptZNgHPiHqvRh Apr 12 20:54:56 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2q41IngpN1TO79Zb7QcOmA","service":"spop","name":"528 Hz Dreamwhisper","artist":"Ashtar Being","album":"Bloomfield","type":"song","duration":64,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a4bff77f5205471b581c385","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:56 volumio volumio[1555]: info: Using cached record of: spotify:track:3lC9KCXXAEMIDzoUcYuJWQ Apr 12 20:54:56 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1yyMnbuD7QfQwib61pAIY8","service":"spop","name":"528 Hz Azureglide","artist":"Ashtar Being","album":"Featherspark","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b273358609ff550b7c4802b30a99","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:56 volumio volumio[1555]: info: Preload queue cleared Apr 12 20:54:56 volumio volumio[1555]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::ClearQueue Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::stop Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::stPlaybackTimer Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::updateTrackBlock Apr 12 20:54:56 volumio volumio[1555]: info: CorePlayQueue::getTrackBlock Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:56 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:56 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 20:54:56 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::serviceStop Apr 12 20:54:56 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:56 volumio volumio[1555]: info: CoreCommandRouter::serviceStop Apr 12 20:54:56 volumio volumio[1555]: info: [1776020096221] ControllerWebradio::stop Apr 12 20:54:56 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 20:54:56 volumio volumio[1555]: info: CorePlayQueue::clearPlayQueue Apr 12 20:54:56 volumio volumio[1555]: info: CorePlayQueue::saveQueue Apr 12 20:54:56 volumio volumio[1555]: info: CoreCommandRouter::volumioPushQueue Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::addQueueItems Apr 12 20:54:56 volumio volumio[1555]: info: CorePlayQueue::addQueueItems Apr 12 20:54:56 volumio volumio[1555]: info: Preload queue cleared Apr 12 20:54:56 volumio volumio[1555]: info: Adding Item to queue: spotify:user:spotify:playlist:6HYYusUEX8ZVNzESrNIkQg Apr 12 20:54:56 volumio volumio[1555]: info: Exploding uri spotify:user:spotify:playlist:6HYYusUEX8ZVNzESrNIkQg in service spop Apr 12 20:54:56 volumio volumio[1555]: SPOTIFY: EXPLODING URI:spotify:user:spotify:playlist:6HYYusUEX8ZVNzESrNIkQg Apr 12 20:54:56 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 12 20:54:56 volumio volumio[1555]: info: Apr 12 20:54:56 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:56 volumio volumio[1555]: info: sendMpdCommand stop took 16 milliseconds Apr 12 20:54:56 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:56 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:56 volumio volumio[1555]: info: Apr 12 20:54:56 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:56 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:56 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:56 volumio volumio[1555]: info: Apr 12 20:54:56 volumio volumio[1555]: ---------------------------- MPD announces state update: player Apr 12 20:54:56 volumio volumio[1555]: info: ControllerMpd::getState Apr 12 20:54:56 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand status Apr 12 20:54:56 volumio volumio[1555]: info: sendMpdCommand status took 3 milliseconds Apr 12 20:54:56 volumio volumio[1555]: info: sendMpdCommand status took 2 milliseconds Apr 12 20:54:56 volumio volumio[1555]: info: sendMpdCommand status took 1 milliseconds Apr 12 20:54:56 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:56 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:56 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:56 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:56 volumio volumio[1555]: verbose: ControllerMpd::parseState Apr 12 20:54:56 volumio volumio[1555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 20:54:56 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 20:54:56 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 20:54:56 volumio volumio[1555]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 20:54:56 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:56 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:56 volumio volumio[1555]: verbose: ControllerMpd::parseTrackInfo Apr 12 20:54:56 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:56 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:56 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:56 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 20:54:56 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:56 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:56 volumio volumio[1555]: 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":"POP mit Michael Reufsteck","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:56 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::syncState stateService stop Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus stop Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:56 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:56 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:56 volumio volumio[1555]: info: No code Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:56 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:56 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:56 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:56 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:56 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:56 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:56 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:56 volumio volumio[1555]: 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":"POP mit Michael Reufsteck","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:56 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::syncState stateService stop Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus stop Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:56 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:56 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:56 volumio volumio[1555]: info: No code Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:56 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:56 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:56 volumio volumio[1555]: info: ControllerMpd::pushState Apr 12 20:54:56 volumio volumio[1555]: info: CoreCommandRouter::servicePushState Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:56 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:56 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:56 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:56 volumio volumio[1555]: 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":"POP mit Michael Reufsteck","artist":"SWR3 MP3 128","album":null,"uri":"http://liveradio.swr.de/tn8jep3/swr3/","trackType":""} Apr 12 20:54:56 volumio volumio[1555]: verbose: CURRENT POSITION 0 Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::syncState stateService stop Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::syncState currentStatus stop Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:56 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:56 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:56 volumio volumio[1555]: info: No code Apr 12 20:54:56 volumio volumio[1555]: info: CoreStateMachine::pushState Apr 12 20:54:56 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:54:56 volumio volumio[1555]: info: CoreCommandRouter::volumioPushState Apr 12 20:54:56 volumio volumio[1555]: info: ------------------------------ 49ms Apr 12 20:54:56 volumio volumio[1555]: info: ------------------------------ 48ms Apr 12 20:54:56 volumio volumio[1555]: info: ------------------------------ 48ms Apr 12 20:54:56 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 12 20:54:56 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 12 20:54:56 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 12 20:54:56 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 12 20:54:56 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 12 20:54:56 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 12 20:54:56 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 12 20:54:56 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 12 20:54:56 volumio volumio[1555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 12 20:54:56 volumio volumio[1555]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 12 20:54:56 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3IU3nWyXJ3fyUdHhqDG3ln","service":"spop","name":"528 Hz Beneath the Stars","artist":"Starlight Aura","album":"Soft Ascension","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b2739fed98b58cef781a517d9778","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:56 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:70fSCYG8DC8UCRRDBl6fL5","service":"spop","name":"528 Hz Vespergaze","artist":"Ashtar Being","album":"Astralume","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b273aad787d75cf4dd406527524b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:54:56 volumio volumio[1555]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7zftUUc5XzXx1e5otojxKg","service":"spop","name":"528 Hz Soul Rest","artist":"Ashtar Being","album":"Dreamkeeper","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b273eb41891a633ee319a6e1749c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 12 20:55:00 volumio python3[1556]: 2026-04-12 20:55:00,111 - INFO - watchdog_reset done. Apr 12 20:55:01 volumio volumio[1555]: info: CoreCommandRouter::volumioPushQueue Apr 12 20:55:01 volumio volumio[1555]: info: CorePlayQueue::saveQueue Apr 12 20:55:01 volumio volumio[1555]: info: CoreStateMachine::updateTrackBlock Apr 12 20:55:01 volumio volumio[1555]: info: CorePlayQueue::getTrackBlock Apr 12 20:55:01 volumio volumio[1555]: info: CoreCommandRouter::volumioPlay Apr 12 20:55:01 volumio volumio[1555]: info: CoreStateMachine::play index 0 Apr 12 20:55:01 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 20:55:01 volumio volumio[1555]: info: CoreStateMachine::stop Apr 12 20:55:01 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 20:55:01 volumio volumio[1555]: info: CoreStateMachine::play index undefined Apr 12 20:55:01 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 20:55:01 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:55:01 volumio volumio[1555]: info: CoreStateMachine::startPlaybackTimer Apr 12 20:55:01 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:55:01 volumio volumio[1555]: info: [1776020101198] ControllerSpotify::clearAddPlayTrack Apr 12 20:55:01 volumio volumio[1555]: info: Sending Spotify command with payload to local API: /player/play Apr 12 20:55:04 volumio volumio[1555]: info: CoreCommandRouter::volumioPlay Apr 12 20:55:04 volumio volumio[1555]: info: CoreStateMachine::play index undefined Apr 12 20:55:04 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 20:55:04 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:55:04 volumio volumio[1555]: info: CoreStateMachine::startPlaybackTimer Apr 12 20:55:04 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:55:04 volumio volumio[1555]: info: [1776020104365] ControllerSpotify::clearAddPlayTrack Apr 12 20:55:04 volumio volumio[1555]: info: Sending Spotify command with payload to local API: /player/play Apr 12 20:55:05 volumio python3[1556]: 2026-04-12 20:55:05,128 - INFO - watchdog_reset done. Apr 12 20:55:05 volumio volumio[1555]: info: Preload queue cleared Apr 12 20:55:05 volumio volumio[1555]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 20:55:05 volumio volumio[1555]: info: CoreStateMachine::ClearQueue Apr 12 20:55:05 volumio volumio[1555]: info: CoreStateMachine::stop Apr 12 20:55:05 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 20:55:05 volumio volumio[1555]: info: CorePlayQueue::clearPlayQueue Apr 12 20:55:05 volumio volumio[1555]: info: CorePlayQueue::saveQueue Apr 12 20:55:05 volumio volumio[1555]: info: CoreCommandRouter::volumioPushQueue Apr 12 20:55:05 volumio volumio[1555]: info: CoreStateMachine::addQueueItems Apr 12 20:55:05 volumio volumio[1555]: info: CorePlayQueue::addQueueItems Apr 12 20:55:05 volumio volumio[1555]: info: Preload queue cleared Apr 12 20:55:05 volumio volumio[1555]: info: Adding Item to queue: spotify:user:spotify:playlist:6HYYusUEX8ZVNzESrNIkQg Apr 12 20:55:05 volumio volumio[1555]: info: Using cached record of: spotify:user:spotify:playlist:6HYYusUEX8ZVNzESrNIkQg Apr 12 20:55:05 volumio volumio[1555]: info: CoreCommandRouter::volumioPushQueue Apr 12 20:55:05 volumio volumio[1555]: info: CorePlayQueue::saveQueue Apr 12 20:55:05 volumio volumio[1555]: info: CoreStateMachine::updateTrackBlock Apr 12 20:55:05 volumio volumio[1555]: info: CorePlayQueue::getTrackBlock Apr 12 20:55:05 volumio volumio[1555]: info: CoreCommandRouter::volumioPlay Apr 12 20:55:05 volumio volumio[1555]: info: CoreStateMachine::play index 0 Apr 12 20:55:05 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 20:55:05 volumio volumio[1555]: info: CoreStateMachine::stop Apr 12 20:55:05 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 20:55:05 volumio volumio[1555]: info: CoreStateMachine::play index undefined Apr 12 20:55:05 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 20:55:05 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:55:05 volumio volumio[1555]: info: CoreStateMachine::startPlaybackTimer Apr 12 20:55:05 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:55:05 volumio volumio[1555]: info: [1776020105543] ControllerSpotify::clearAddPlayTrack Apr 12 20:55:05 volumio volumio[1555]: info: Sending Spotify command with payload to local API: /player/play Apr 12 20:55:08 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Apr 12 20:55:08 volumio volumio[1555]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:6HYYusUEX8ZVNzESrNIkQg Apr 12 20:55:10 volumio python3[1556]: 2026-04-12 20:55:10,144 - INFO - watchdog_reset done. Apr 12 20:55:14 volumio volumio[1555]: info: Preload queue cleared Apr 12 20:55:15 volumio python3[1556]: 2026-04-12 20:55:15,162 - INFO - watchdog_reset done. Apr 12 20:55:20 volumio python3[1556]: 2026-04-12 20:55:20,178 - INFO - watchdog_reset done. Apr 12 20:55:25 volumio python3[1556]: 2026-04-12 20:55:25,195 - INFO - watchdog_reset done. Apr 12 20:55:30 volumio python3[1556]: 2026-04-12 20:55:30,212 - INFO - watchdog_reset done. Apr 12 20:55:32 volumio volumio[1555]: verbose: New Socket.io Connection to 192.168.10.88 from 192.168.10.21 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Apr 12 20:55:32 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 12 20:55:32 volumio volumio[1555]: info: CoreCommandRouter::volumioGetVisibleSources Apr 12 20:55:32 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 20:55:32 volumio volumio[1555]: info: CoreCommandRouter::volumioGetState Apr 12 20:55:32 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:55:32 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 12 20:55:32 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 12 20:55:32 volumio volumio[1555]: info: Received Get System Info Apr 12 20:55:32 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 20:55:32 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 20:55:32 volumio volumio[1555]: info: Discovery: Getting this device information Apr 12 20:55:32 volumio volumio[1555]: info: CoreCommandRouter::volumioGetState Apr 12 20:55:32 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:55:32 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 20:55:32 volumio volumio[1555]: info: CoreCommandRouter::volumioGetState Apr 12 20:55:32 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:55:32 volumio volumio[1555]: info: Listing playlists Apr 12 20:55:32 volumio volumio[1555]: info: CoreCommandRouter::volumioGetQueue Apr 12 20:55:32 volumio volumio[1555]: info: CoreStateMachine::getQueue Apr 12 20:55:32 volumio volumio[1555]: info: CorePlayQueue::getQueue Apr 12 20:55:32 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 12 20:55:33 volumio volumio[1555]: info: CoreCommandRouter::volumioPlay Apr 12 20:55:33 volumio volumio[1555]: info: CoreStateMachine::play index undefined Apr 12 20:55:33 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 20:55:33 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:55:33 volumio volumio[1555]: info: CoreStateMachine::startPlaybackTimer Apr 12 20:55:33 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:55:33 volumio volumio[1555]: info: [1776020133678] ControllerSpotify::clearAddPlayTrack Apr 12 20:55:33 volumio volumio[1555]: info: Sending Spotify command with payload to local API: /player/play Apr 12 20:55:35 volumio python3[1556]: 2026-04-12 20:55:35,228 - INFO - watchdog_reset done. Apr 12 20:55:35 volumio volumio[1555]: info: CoreCommandRouter::volumioPlay Apr 12 20:55:35 volumio volumio[1555]: info: CoreStateMachine::play index undefined Apr 12 20:55:35 volumio volumio[1555]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 20:55:35 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:55:35 volumio volumio[1555]: info: CoreStateMachine::startPlaybackTimer Apr 12 20:55:35 volumio volumio[1555]: info: CorePlayQueue::getTrack 0 Apr 12 20:55:35 volumio volumio[1555]: info: [1776020135642] ControllerSpotify::clearAddPlayTrack Apr 12 20:55:35 volumio volumio[1555]: info: Sending Spotify command with payload to local API: /player/play Apr 12 20:55:40 volumio python3[1556]: 2026-04-12 20:55:40,245 - INFO - watchdog_reset done. Apr 12 20:55:43 volumio wpa_supplicant[1451]: wlan0: WPA: Group rekeying completed with 2c:91:ab:2c:fb:92 [GTK=CCMP] Apr 12 20:55:44 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 12 20:55:44 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 12 20:55:45 volumio python3[1556]: 2026-04-12 20:55:45,262 - INFO - watchdog_reset done. Apr 12 20:55:46 volumio volumio[1555]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 20:55:47 volumio volumio[1555]: info: CALLMETHOD: music_service spop logout Apr 12 20:55:47 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: spop , logout Apr 12 20:55:47 volumio volumio[1555]: info: Deleting Spotify credentials File Apr 12 20:55:47 volumio volumio[1555]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Apr 12 20:55:47 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 20:55:48 volumio volumio[1555]: info: Creating Spotify config file Apr 12 20:55:48 volumio volumio[1555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 20:55:48 volumio volumio[1555]: info: Spotify config file written Apr 12 20:55:48 volumio sudo[2904]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 12 20:55:48 volumio sudo[2904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 20:55:48 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Apr 12 20:55:48 volumio systemd[1]: go-librespot-daemon.service: Killing process 1912 (go-librespot) with signal SIGKILL. Apr 12 20:55:48 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Apr 12 20:55:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 12 20:55:48 volumio volumio[1555]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Apr 12 20:55:48 volumio volumio[1555]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Apr 12 20:55:48 volumio volumio[1555]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Apr 12 20:55:48 volumio volumio[1555]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Apr 12 20:55:48 volumio volumio[1555]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Apr 12 20:55:48 volumio volumio[1555]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 20:55:48 volumio volumio[1555]: Error: socket hang up Apr 12 20:55:48 volumio volumio[1555]: at connResetException (node:internal/errors:720:14) Apr 12 20:55:48 volumio volumio[1555]: at Socket.socketOnEnd (node:_http_client:519:23) Apr 12 20:55:48 volumio volumio[1555]: at Socket.emit (node:events:526:35) Apr 12 20:55:48 volumio volumio[1555]: at endReadableNT (node:internal/streams/readable:1376:12) Apr 12 20:55:48 volumio volumio[1555]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Apr 12 20:55:48 volumio volumio[1555]: code: 'ECONNRESET', Apr 12 20:55:48 volumio volumio[1555]: response: undefined Apr 12 20:55:48 volumio volumio[1555]: } Apr 12 20:55:48 volumio volumio[1555]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 20:55:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 12 20:55:48 volumio go-librespot[2906]: go-librespot daemon starting... Apr 12 20:55:48 volumio sudo[2904]: pam_unix(sudo:session): session closed for user root Apr 12 20:55:48 volumio go-librespot[2918]: time="2026-04-12T20:55:48+02:00" level=info msg="running go-librespot 0.4.0" Apr 12 20:55:48 volumio go-librespot[2918]: time="2026-04-12T20:55:48+02:00" level=debug msg="no app state found" Apr 12 20:55:48 volumio go-librespot[2918]: time="2026-04-12T20:55:48+02:00" level=debug msg="stored credentials not found" Apr 12 20:55:48 volumio go-librespot[2918]: time="2026-04-12T20:55:48+02:00" level=info msg="generated new device id: b0fd2e8adea9128d7357650cb80ed64c2d2c856e" Apr 12 20:55:48 volumio go-librespot[2918]: time="2026-04-12T20:55:48+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 12 20:55:49 volumio go-librespot[2918]: time="2026-04-12T20:55:49+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Apr 12 20:55:49 volumio go-librespot[2918]: time="2026-04-12T20:55:49+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Apr 12 20:55:49 volumio go-librespot[2918]: time="2026-04-12T20:55:49+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Apr 12 20:55:49 volumio go-librespot[2918]: time="2026-04-12T20:55:49+02:00" level=info msg="zeroconf server listening on port 39479" Apr 12 20:55:49 volumio sudo[2928]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-12 20:54' Apr 12 20:55:49 volumio sudo[2928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="5ef3d56f987d109f3e21bfa246dbbb723cf6a917" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="c7bff9654d2984a81c5720476c2c8ac531869c54" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Oct 9 18:08:11 UTC 2025" VOLUMIO_VERSION="4.062" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="00524295c246b7d72f125e4c54b86a88"