Mar 25 21:37:24 volumio go-librespot[1595]: time="2026-03-25T21:37:24+02:00" level=trace msg="sent dealer ping" Mar 25 21:37:24 volumio go-librespot[1595]: time="2026-03-25T21:37:24+02:00" level=trace msg="received dealer pong" Mar 25 21:37:25 volumio volumio[1240]: info: Mar 25 21:37:25 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:37:25 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:37:25 volumio volumio[1240]: info: Mar 25 21:37:25 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:37:25 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:37:25 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:37:25 volumio volumio[1240]: info: Mar 25 21:37:25 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:37:25 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:37:25 volumio volumio[1240]: info: Mar 25 21:37:25 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:37:25 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:37:25 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:37:25 volumio volumio[1240]: info: Mar 25 21:37:25 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:37:25 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:37:25 volumio volumio[1240]: info: Mar 25 21:37:25 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:37:25 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:37:25 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:37:25 volumio volumio[1240]: info: ------------------------------ 2ms Mar 25 21:37:25 volumio volumio[1240]: info: sendMpdCommand status took 2 milliseconds Mar 25 21:37:25 volumio volumio[1240]: info: ------------------------------ 1ms Mar 25 21:37:25 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:37:25 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:37:25 volumio volumio[1240]: info: sendMpdCommand status took 2 milliseconds Mar 25 21:37:25 volumio volumio[1240]: info: ------------------------------ 2ms Mar 25 21:37:25 volumio volumio[1240]: info: sendMpdCommand status took 1 milliseconds Mar 25 21:37:25 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 25 21:37:25 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:37:25 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:37:25 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:37:25 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:37:25 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:37:25 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:37:25 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:37:25 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:25 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69188,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Saban Saulic - Samo za nju","artist":"OK Radio 94.2FM","album":null,"uri":"http://sslstream.okradio.net:8000/","trackType":"net:8000/"} Mar 25 21:37:25 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:37:25 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:37:25 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:37:25 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:37:25 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:37:25 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:25 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 21:37:25 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:25 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:25 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:25 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:25.906+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=68320 volume=39 Mar 25 21:37:25 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:25.906+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=68320 volume=39 Mar 25 21:37:25 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:25.906+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:25 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:25.906+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:25 volumio volumio[1240]: info: ------------------------------ 9ms Mar 25 21:37:25 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 25 21:37:25 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 25 21:37:25 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:37:25 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:37:25 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:37:25 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:37:25 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:25 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69188,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Saban Saulic - Samo za nju","artist":"OK Radio 94.2FM","album":null,"uri":"http://sslstream.okradio.net:8000/","trackType":"net:8000/"} Mar 25 21:37:25 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:37:25 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:37:25 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:37:25 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:37:25 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:37:25 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:25 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:25 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:25 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:25 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:37:25 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:37:25 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:25 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69188,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Saban Saulic - Samo za nju","artist":"OK Radio 94.2FM","album":null,"uri":"http://sslstream.okradio.net:8000/","trackType":"net:8000/"} Mar 25 21:37:25 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:37:25 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:37:25 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:37:25 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:37:25 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:37:25 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:25 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:25 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:25 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:25 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:25.914+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=68320 volume=39 Mar 25 21:37:25 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:25.914+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=68320 volume=39 Mar 25 21:37:25 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:25.915+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=68320 volume=39 Mar 25 21:37:25 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:25.915+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:25 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:25.915+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:25 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:25.915+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:25 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:25.915+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=68320 volume=39 Mar 25 21:37:25 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:25.916+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:25 volumio volumio[1240]: info: ------------------------------ 19ms Mar 25 21:37:25 volumio volumio[1240]: info: ------------------------------ 18ms Mar 25 21:37:25 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:25 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:25 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:25 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:25 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:25 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:54 volumio go-librespot[1595]: time="2026-03-25T21:37:54+02:00" level=trace msg="sent dealer ping" Mar 25 21:37:54 volumio go-librespot[1595]: time="2026-03-25T21:37:54+02:00" level=trace msg="received dealer pong" Mar 25 21:37:56 volumio volumio[1240]: info: Preload queue cleared Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::ClearQueue Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::stop Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::stPlaybackTimer Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::updateTrackBlock Mar 25 21:37:56 volumio volumio[1240]: info: CorePlayQueue::getTrackBlock Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::serviceStop Mar 25 21:37:56 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::serviceStop Mar 25 21:37:56 volumio volumio[1240]: info: [1774467476878] ControllerWebradio::stop Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 21:37:56 volumio volumio[1240]: info: CorePlayQueue::clearPlayQueue Mar 25 21:37:56 volumio volumio[1240]: info: CorePlayQueue::saveQueue Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushQueue Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::addQueueItems Mar 25 21:37:56 volumio volumio[1240]: info: CorePlayQueue::addQueueItems Mar 25 21:37:56 volumio volumio[1240]: info: Preload queue cleared Mar 25 21:37:56 volumio volumio[1240]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958 Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 25 21:37:56 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:56.879+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_STOPPED positionMs=0 volume=39 Mar 25 21:37:56 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:56.880+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99616827" title="OK Radio 94.2FM" Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushQueue Mar 25 21:37:56 volumio volumio[1240]: info: CorePlayQueue::saveQueue Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::updateTrackBlock Mar 25 21:37:56 volumio volumio[1240]: info: CorePlayQueue::getTrackBlock Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPlay Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::play index 0 Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::stop Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::play index undefined Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 21:37:56 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::startPlaybackTimer Mar 25 21:37:56 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:56 volumio volumio[1240]: info: [1774467476884] ControllerWebradio::clearAddPlayTrack Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 21:37:56 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:56 volumio volumio[1240]: info: Mar 25 21:37:56 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand stop took 11 milliseconds Mar 25 21:37:56 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand stop took 5 milliseconds Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 21:37:56 volumio volumio[1240]: info: Mar 25 21:37:56 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:37:56 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:37:56 volumio volumio[1240]: info: Mar 25 21:37:56 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:37:56 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:37:56 volumio volumio[1240]: info: Mar 25 21:37:56 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:37:56 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand status took 2 milliseconds Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand clear took 2 milliseconds Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand status took 1 milliseconds Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand status took 2 milliseconds Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:37:56 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:56 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:56 volumio volumio[1240]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 21:37:56 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:56 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:56 volumio volumio[1240]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 21:37:56 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:56.896+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_STOPPED positionMs=0 volume=39 Mar 25 21:37:56 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:56.896+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_STOPPED positionMs=0 volume=39 Mar 25 21:37:56 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:56.896+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" title="ANTENNE BAYERN Oldies but Goldies (Germany)" Mar 25 21:37:56 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:56.897+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" title="ANTENNE BAYERN Oldies but Goldies (Germany)" Mar 25 21:37:56 volumio volumio[1240]: info: ------------------------------ 7ms Mar 25 21:37:56 volumio volumio[1240]: info: ------------------------------ 8ms Mar 25 21:37:56 volumio volumio[1240]: info: Mar 25 21:37:56 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:37:56 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:37:56 volumio volumio[1240]: info: Mar 25 21:37:56 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:37:56 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:37:56 volumio volumio[1240]: error: updateQueue error: null Mar 25 21:37:56 volumio volumio[1240]: info: ------------------------------ 8ms Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:37:56 volumio volumio[1240]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 25 21:37:56 volumio volumio[1240]: info: ------------------------------ 12ms Mar 25 21:37:56 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:56 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand add "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Mar 25 21:37:56 volumio volumio[1240]: error: updateQueue error: null Mar 25 21:37:56 volumio volumio[1240]: error: updateQueue error: null Mar 25 21:37:56 volumio volumio[1240]: info: ------------------------------ 51ms Mar 25 21:37:56 volumio volumio[1240]: info: ------------------------------ 51ms Mar 25 21:37:56 volumio volumio[1240]: info: Mar 25 21:37:56 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:37:56 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand add "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 2 milliseconds Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand play Mar 25 21:37:56 volumio volumio[1240]: info: Mar 25 21:37:56 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:37:56 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:37:56 volumio volumio[1240]: info: Mar 25 21:37:56 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:37:56 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:37:56 volumio volumio[1240]: info: ------------------------------ 4ms Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand play took 2 milliseconds Mar 25 21:37:56 volumio volumio[1240]: info: ------------------------------ 2ms Mar 25 21:37:56 volumio volumio[1240]: info: ------------------------------ 1ms Mar 25 21:37:56 volumio volumio[1240]: info: Mar 25 21:37:56 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:37:56 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:37:56 volumio volumio[1240]: info: Mar 25 21:37:56 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:37:56 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:37:56 volumio volumio[1240]: info: Mar 25 21:37:56 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:37:56 volumio volumio[1240]: info: Mar 25 21:37:56 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:37:56 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:37:56 volumio volumio[1240]: error: MPD returned error for command status: Failed to decode "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958"; Failed to connect to yp.shoutcast.com port 80 after 3 ms: Couldn't connect to server: Couldn't connect to server Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand status took 4 milliseconds Mar 25 21:37:56 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:37:56 volumio volumio[1240]: info: Mar 25 21:37:56 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:37:56 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:37:56 volumio volumio[1240]: info: Mar 25 21:37:56 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:37:56 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:37:56 volumio volumio[1240]: error: MPD returned error for command status: Failed to decode "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958"; Failed to connect to yp.shoutcast.com port 80 after 3 ms: Couldn't connect to server: Couldn't connect to server Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand status took 9 milliseconds Mar 25 21:37:56 volumio volumio[1240]: error: MPD returned error for command status: Failed to decode "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958"; Failed to connect to yp.shoutcast.com port 80 after 3 ms: Couldn't connect to server: Couldn't connect to server Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand status took 9 milliseconds Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand clearerror took 6 milliseconds Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand status took 6 milliseconds Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand status took 5 milliseconds Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:37:56 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:56 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:56 volumio volumio[1240]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"tunein-station.m3u?id=99497958","artist":null,"album":null,"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958","trackType":""} Mar 25 21:37:56 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::syncState stateService stop Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus stop Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:56 volumio volumio[1240]: info: No code Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:56 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:56.977+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:37:56 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:56.977+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:56 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:56.977+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:37:56 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:56.977+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:37:56 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:56.977+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:56 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:56.977+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:56 volumio volumio[1240]: info: ------------------------------ 21ms Mar 25 21:37:56 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:56 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:56 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand status took 19 milliseconds Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand clearerror took 17 milliseconds Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand clearerror took 17 milliseconds Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 25 21:37:56 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:37:56 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:37:56 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:56 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:56 volumio volumio[1240]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"tunein-station.m3u?id=99497958","artist":null,"album":null,"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958","trackType":""} Mar 25 21:37:56 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::syncState stateService stop Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus stop Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:56 volumio volumio[1240]: info: No code Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:56 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:56 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:56 volumio volumio[1240]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"tunein-station.m3u?id=99497958","artist":null,"album":null,"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958","trackType":""} Mar 25 21:37:56 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::syncState stateService stop Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus stop Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:56 volumio volumio[1240]: info: No code Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:56 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:56 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:56 volumio volumio[1240]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"tunein-station.m3u?id=99497958","artist":null,"album":null,"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958","trackType":""} Mar 25 21:37:56 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::syncState stateService stop Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus stop Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:56 volumio volumio[1240]: info: No code Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:56 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:56 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:56 volumio volumio[1240]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"tunein-station.m3u?id=99497958","artist":null,"album":null,"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958","trackType":""} Mar 25 21:37:56 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::syncState stateService stop Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus stop Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:56 volumio volumio[1240]: info: No code Mar 25 21:37:56 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:56 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:56.999+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.000+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.000+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.000+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.000+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.001+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.001+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.001+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.002+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.001+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.001+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.002+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.001+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.001+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.003+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.001+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.003+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.003+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.004+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.005+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.005+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.005+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.005+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.005+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:57 volumio volumio[1240]: info: ------------------------------ 51ms Mar 25 21:37:57 volumio volumio[1240]: info: ------------------------------ 50ms Mar 25 21:37:57 volumio volumio[1240]: info: ------------------------------ 51ms Mar 25 21:37:57 volumio volumio[1240]: info: ------------------------------ 47ms Mar 25 21:37:57 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 25 milliseconds Mar 25 21:37:57 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:37:57 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:37:57 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:37:57 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:57 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:57 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:57 volumio volumio[1240]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"tunein-station.m3u?id=99497958","artist":null,"album":null,"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958","trackType":""} Mar 25 21:37:57 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:37:57 volumio volumio[1240]: info: CoreStateMachine::syncState stateService stop Mar 25 21:37:57 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus stop Mar 25 21:37:57 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:57 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:57 volumio volumio[1240]: info: No code Mar 25 21:37:57 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:57 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.016+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.017+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.018+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.018+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.019+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:37:57 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:57.019+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:57 volumio volumio[1240]: info: ------------------------------ 55ms Mar 25 21:37:57 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:57 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:57 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:57 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:57 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:57 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:57 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:57 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:57 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:57 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:57 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:57 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:57 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:57 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:57 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:59 volumio volumio[1240]: info: Preload queue cleared Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::ClearQueue Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::stop Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 21:37:59 volumio volumio[1240]: info: CorePlayQueue::clearPlayQueue Mar 25 21:37:59 volumio volumio[1240]: info: CorePlayQueue::saveQueue Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPushQueue Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::addQueueItems Mar 25 21:37:59 volumio volumio[1240]: info: CorePlayQueue::addQueueItems Mar 25 21:37:59 volumio volumio[1240]: info: Preload queue cleared Mar 25 21:37:59 volumio volumio[1240]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323 Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPushQueue Mar 25 21:37:59 volumio volumio[1240]: info: CorePlayQueue::saveQueue Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::updateTrackBlock Mar 25 21:37:59 volumio volumio[1240]: info: CorePlayQueue::getTrackBlock Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPlay Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::play index 0 Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::stop Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::play index undefined Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 21:37:59 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::startPlaybackTimer Mar 25 21:37:59 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:59 volumio volumio[1240]: info: [1774467479121] ControllerWebradio::clearAddPlayTrack Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 21:37:59 volumio volumio[1240]: info: sendMpdCommand stop took 2 milliseconds Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 21:37:59 volumio volumio[1240]: info: Mar 25 21:37:59 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:37:59 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:37:59 volumio volumio[1240]: info: sendMpdCommand clear took 1 milliseconds Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323" Mar 25 21:37:59 volumio volumio[1240]: info: Mar 25 21:37:59 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:37:59 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:37:59 volumio volumio[1240]: info: Mar 25 21:37:59 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:37:59 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:37:59 volumio volumio[1240]: error: updateQueue error: null Mar 25 21:37:59 volumio volumio[1240]: info: ------------------------------ 5ms Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand add "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323" Mar 25 21:37:59 volumio volumio[1240]: error: updateQueue error: null Mar 25 21:37:59 volumio volumio[1240]: error: updateQueue error: null Mar 25 21:37:59 volumio volumio[1240]: info: Mar 25 21:37:59 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:37:59 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:37:59 volumio volumio[1240]: info: ------------------------------ 5ms Mar 25 21:37:59 volumio volumio[1240]: info: ------------------------------ 4ms Mar 25 21:37:59 volumio volumio[1240]: info: sendMpdCommand add "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323" took 1 milliseconds Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand play Mar 25 21:37:59 volumio volumio[1240]: info: Mar 25 21:37:59 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:37:59 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:37:59 volumio volumio[1240]: info: Mar 25 21:37:59 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:37:59 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:37:59 volumio volumio[1240]: info: ------------------------------ 4ms Mar 25 21:37:59 volumio volumio[1240]: info: sendMpdCommand play took 4 milliseconds Mar 25 21:37:59 volumio volumio[1240]: info: ------------------------------ 1ms Mar 25 21:37:59 volumio volumio[1240]: info: ------------------------------ 1ms Mar 25 21:37:59 volumio volumio[1240]: info: Mar 25 21:37:59 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:37:59 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:37:59 volumio volumio[1240]: info: Mar 25 21:37:59 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:37:59 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:37:59 volumio volumio[1240]: info: Mar 25 21:37:59 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:37:59 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:37:59 volumio volumio[1240]: info: Mar 25 21:37:59 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:37:59 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:37:59 volumio volumio[1240]: info: Mar 25 21:37:59 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:37:59 volumio volumio[1240]: error: MPD returned error for command status: Failed to decode "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323"; avformat_open_input() failed: Invalid data found when processing input Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 25 21:37:59 volumio volumio[1240]: info: sendMpdCommand status took 4 milliseconds Mar 25 21:37:59 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:37:59 volumio volumio[1240]: error: MPD returned error for command status: Failed to decode "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323"; avformat_open_input() failed: Invalid data found when processing input Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 25 21:37:59 volumio volumio[1240]: info: sendMpdCommand status took 3 milliseconds Mar 25 21:37:59 volumio volumio[1240]: error: MPD returned error for command status: Failed to decode "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323"; avformat_open_input() failed: Invalid data found when processing input Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 25 21:37:59 volumio volumio[1240]: info: sendMpdCommand status took 4 milliseconds Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:37:59 volumio volumio[1240]: info: Mar 25 21:37:59 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:37:59 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:37:59 volumio volumio[1240]: error: MPD returned error for command status: Failed to decode "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323"; avformat_open_input() failed: Invalid data found when processing input Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 25 21:37:59 volumio volumio[1240]: info: sendMpdCommand status took 7 milliseconds Mar 25 21:37:59 volumio volumio[1240]: info: sendMpdCommand clearerror took 5 milliseconds Mar 25 21:37:59 volumio volumio[1240]: info: sendMpdCommand status took 5 milliseconds Mar 25 21:37:59 volumio volumio[1240]: info: sendMpdCommand clearerror took 5 milliseconds Mar 25 21:37:59 volumio volumio[1240]: info: sendMpdCommand clearerror took 4 milliseconds Mar 25 21:37:59 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 25 21:37:59 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 25 21:37:59 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 25 21:37:59 volumio volumio[1240]: info: sendMpdCommand status took 2 milliseconds Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:37:59 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:59 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:59 volumio volumio[1240]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"tunein-station.m3u?id=99568323","artist":null,"album":null,"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323","trackType":""} Mar 25 21:37:59 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::syncState stateService stop Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus stop Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:59 volumio volumio[1240]: info: No code Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:59 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:59 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:59 volumio volumio[1240]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"tunein-station.m3u?id=99568323","artist":null,"album":null,"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323","trackType":""} Mar 25 21:37:59 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::syncState stateService stop Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus stop Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:59 volumio volumio[1240]: info: No code Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:59 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:59 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:59 volumio volumio[1240]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"tunein-station.m3u?id=99568323","artist":null,"album":null,"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323","trackType":""} Mar 25 21:37:59 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::syncState stateService stop Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus stop Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:59 volumio volumio[1240]: info: No code Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.394+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=250 volume=39 Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.394+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=250 volume=39 Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.394+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=250 volume=39 Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.394+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=250 volume=39 Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.394+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.395+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.395+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.395+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=250 volume=39 Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.395+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.395+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=250 volume=39 Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.396+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=250 volume=39 Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.396+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.396+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=250 volume=39 Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.396+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=250 volume=39 Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.397+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.398+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.399+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.399+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:59 volumio volumio[1240]: info: ------------------------------ 25ms Mar 25 21:37:59 volumio volumio[1240]: info: ------------------------------ 25ms Mar 25 21:37:59 volumio volumio[1240]: info: ------------------------------ 25ms Mar 25 21:37:59 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:59 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:59 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:59 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:59 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:59 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:59 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:59 volumio volumio[1240]: info: sendMpdCommand clearerror took 24 milliseconds Mar 25 21:37:59 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 23 milliseconds Mar 25 21:37:59 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 23 milliseconds Mar 25 21:37:59 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 23 milliseconds Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:37:59 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:37:59 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:59 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:59 volumio volumio[1240]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"tunein-station.m3u?id=99568323","artist":null,"album":null,"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323","trackType":""} Mar 25 21:37:59 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::syncState stateService stop Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus stop Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:59 volumio volumio[1240]: info: No code Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:59 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:59 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:59 volumio volumio[1240]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"tunein-station.m3u?id=99568323","artist":null,"album":null,"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323","trackType":""} Mar 25 21:37:59 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::syncState stateService stop Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus stop Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:59 volumio volumio[1240]: info: No code Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:59 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:59 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:37:59 volumio volumio[1240]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"tunein-station.m3u?id=99568323","artist":null,"album":null,"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323","trackType":""} Mar 25 21:37:59 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::syncState stateService stop Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus stop Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:59 volumio volumio[1240]: info: No code Mar 25 21:37:59 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:37:59 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.419+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=286 volume=39 Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.419+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=286 volume=39 Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.420+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.420+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=286 volume=39 Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.420+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=286 volume=39 Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.420+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.420+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.421+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.421+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=286 volume=39 Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.421+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=286 volume=39 Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.421+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.422+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=286 volume=39 Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.422+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.422+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.422+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=286 volume=39 Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.423+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.423+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=286 volume=39 Mar 25 21:37:59 volumio volumio5-onboarding[1522]: time=2026-03-25T21:37:59.423+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://sslstream.okradio.net:8000/ title="Saban Saulic - Samo za nju" Mar 25 21:37:59 volumio volumio[1240]: info: ------------------------------ 48ms Mar 25 21:37:59 volumio volumio[1240]: info: ------------------------------ 49ms Mar 25 21:37:59 volumio volumio[1240]: info: ------------------------------ 44ms Mar 25 21:37:59 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:59 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:59 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:59 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:59 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:59 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:59 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:59 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:59 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:59 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:37:59 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:03 volumio volumio[1240]: info: Preload queue cleared Mar 25 21:38:03 volumio volumio[1240]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 21:38:03 volumio volumio[1240]: info: CoreStateMachine::ClearQueue Mar 25 21:38:03 volumio volumio[1240]: info: CoreStateMachine::stop Mar 25 21:38:03 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 21:38:03 volumio volumio[1240]: info: CorePlayQueue::clearPlayQueue Mar 25 21:38:03 volumio volumio[1240]: info: CorePlayQueue::saveQueue Mar 25 21:38:03 volumio volumio[1240]: info: CoreCommandRouter::volumioPushQueue Mar 25 21:38:03 volumio volumio[1240]: info: CoreStateMachine::addQueueItems Mar 25 21:38:03 volumio volumio[1240]: info: CorePlayQueue::addQueueItems Mar 25 21:38:03 volumio volumio[1240]: info: Preload queue cleared Mar 25 21:38:03 volumio volumio[1240]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99615939 Mar 25 21:38:03 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 25 21:38:03 volumio volumio[1240]: info: CoreCommandRouter::volumioPushQueue Mar 25 21:38:03 volumio volumio[1240]: info: CorePlayQueue::saveQueue Mar 25 21:38:03 volumio volumio[1240]: info: CoreStateMachine::updateTrackBlock Mar 25 21:38:03 volumio volumio[1240]: info: CorePlayQueue::getTrackBlock Mar 25 21:38:03 volumio volumio[1240]: info: CoreCommandRouter::volumioPlay Mar 25 21:38:03 volumio volumio[1240]: info: CoreStateMachine::play index 0 Mar 25 21:38:03 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 21:38:03 volumio volumio[1240]: info: CoreStateMachine::stop Mar 25 21:38:03 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 21:38:03 volumio volumio[1240]: info: CoreStateMachine::play index undefined Mar 25 21:38:03 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 21:38:03 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:03 volumio volumio[1240]: info: CoreStateMachine::startPlaybackTimer Mar 25 21:38:03 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:03 volumio volumio[1240]: info: [1774467483472] ControllerWebradio::clearAddPlayTrack Mar 25 21:38:03 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 21:38:03 volumio volumio[1240]: info: sendMpdCommand stop took 2 milliseconds Mar 25 21:38:03 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 21:38:03 volumio volumio[1240]: info: Mar 25 21:38:03 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:03 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:03 volumio volumio[1240]: info: sendMpdCommand clear took 1 milliseconds Mar 25 21:38:03 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99615939" Mar 25 21:38:03 volumio volumio[1240]: info: Mar 25 21:38:03 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:03 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:03 volumio volumio[1240]: info: Mar 25 21:38:03 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:03 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:03 volumio volumio[1240]: error: updateQueue error: null Mar 25 21:38:03 volumio volumio[1240]: info: ------------------------------ 2ms Mar 25 21:38:03 volumio volumio[1240]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99615939" took 126 milliseconds Mar 25 21:38:03 volumio volumio[1240]: info: ------------------------------ 125ms Mar 25 21:38:03 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 21:38:03 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand play Mar 25 21:38:03 volumio volumio[1240]: info: Mar 25 21:38:03 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:03 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:03 volumio volumio[1240]: info: Mar 25 21:38:03 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:03 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:03 volumio volumio[1240]: info: ------------------------------ 127ms Mar 25 21:38:03 volumio volumio[1240]: info: sendMpdCommand play took 2 milliseconds Mar 25 21:38:03 volumio volumio[1240]: info: ------------------------------ 2ms Mar 25 21:38:03 volumio volumio[1240]: info: Mar 25 21:38:03 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:03 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:03 volumio volumio[1240]: info: ------------------------------ 2ms Mar 25 21:38:03 volumio volumio[1240]: info: ------------------------------ 1ms Mar 25 21:38:04 volumio volumio[1240]: info: Mar 25 21:38:04 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:04 volumio volumio[1240]: info: Mar 25 21:38:04 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:04 volumio volumio[1240]: info: Mar 25 21:38:04 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:04 volumio volumio[1240]: info: Mar 25 21:38:04 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand status took 30 milliseconds Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand status took 30 milliseconds Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand status took 29 milliseconds Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand status took 1 milliseconds Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:04 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:04 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 4427/6144) [EN] OSNA.FM | Wave of Osnabr?ck | Ad-free","album":null,"uri":"https://shoutcast.osna.fm:8800/","trackType":"fm:8800/"} Mar 25 21:38:04 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus stop Mar 25 21:38:04 volumio volumio[1240]: info: ------------------------------ 33ms Mar 25 21:38:04 volumio volumio[1240]: info: Mar 25 21:38:04 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:04 volumio volumio[1240]: info: Mar 25 21:38:04 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand status took 2 milliseconds Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand status took 1 milliseconds Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:04 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:04 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 4427/6144) [EN] OSNA.FM | Wave of Osnabr?ck | Ad-free","album":null,"uri":"https://shoutcast.osna.fm:8800/","trackType":"fm:8800/"} Mar 25 21:38:04 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:38:04 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:04 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:04 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 4427/6144) [EN] OSNA.FM | Wave of Osnabr?ck | Ad-free","album":null,"uri":"https://shoutcast.osna.fm:8800/","trackType":"fm:8800/"} Mar 25 21:38:04 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:38:04 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:04 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:04 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 4427/6144) [EN] OSNA.FM | Wave of Osnabr?ck | Ad-free","album":null,"uri":"https://shoutcast.osna.fm:8800/","trackType":"fm:8800/"} Mar 25 21:38:04 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:38:04 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.325+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.325+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title= Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.326+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.326+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title= Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.326+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.326+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title= Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.327+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.328+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title= Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.327+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.328+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title= Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.328+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.328+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title= Mar 25 21:38:04 volumio volumio[1240]: info: ------------------------------ 51ms Mar 25 21:38:04 volumio volumio[1240]: info: ------------------------------ 52ms Mar 25 21:38:04 volumio volumio[1240]: info: ------------------------------ 23ms Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 18 milliseconds Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:04 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:04 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 4427/6144) [EN] OSNA.FM | Wave of Osnabr?ck | Ad-free","album":null,"uri":"https://shoutcast.osna.fm:8800/","trackType":"fm:8800/"} Mar 25 21:38:04 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:38:04 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:04 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:04 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 4427/6144) [EN] OSNA.FM | Wave of Osnabr?ck | Ad-free","album":null,"uri":"https://shoutcast.osna.fm:8800/","trackType":"fm:8800/"} Mar 25 21:38:04 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:38:04 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.337+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.338+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.338+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title= Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.338+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.338+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title= Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.338+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.338+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title= Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.338+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title= Mar 25 21:38:04 volumio volumio[1240]: info: ------------------------------ 29ms Mar 25 21:38:04 volumio volumio[1240]: info: ------------------------------ 29ms Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: info: Mar 25 21:38:04 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:04 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:04 volumio volumio[1240]: info: Mar 25 21:38:04 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:04 volumio volumio[1240]: info: Mar 25 21:38:04 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:04 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:04 volumio volumio[1240]: info: Mar 25 21:38:04 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:04 volumio volumio[1240]: info: Mar 25 21:38:04 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:04 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:04 volumio volumio[1240]: info: Mar 25 21:38:04 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:04 volumio volumio[1240]: info: Mar 25 21:38:04 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:04 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:04 volumio volumio[1240]: info: Mar 25 21:38:04 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:04 volumio volumio[1240]: info: ------------------------------ 3ms Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand status took 2 milliseconds Mar 25 21:38:04 volumio volumio[1240]: info: ------------------------------ 1ms Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:04 volumio volumio[1240]: info: Mar 25 21:38:04 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:04 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:04 volumio volumio[1240]: info: Mar 25 21:38:04 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:04 volumio volumio[1240]: info: Mar 25 21:38:04 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:04 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:04 volumio volumio[1240]: info: Mar 25 21:38:04 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand status took 5 milliseconds Mar 25 21:38:04 volumio volumio[1240]: info: ------------------------------ 6ms Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand status took 5 milliseconds Mar 25 21:38:04 volumio volumio[1240]: info: ------------------------------ 5ms Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand status took 5 milliseconds Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 25 21:38:04 volumio volumio[1240]: info: ------------------------------ 4ms Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand status took 2 milliseconds Mar 25 21:38:04 volumio volumio[1240]: info: ------------------------------ 2ms Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:04 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:04 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"20:37 - Osna.FM Live","artist":"[EN] OSNA.FM | Wave of Osnabrück | Ad-free","album":null,"uri":"https://shoutcast.osna.fm:8800/","trackType":"fm:8800/"} Mar 25 21:38:04 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:38:04 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio[1240]: info: ------------------------------ 15ms Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand status took 10 milliseconds Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.397+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=149 volume=39 Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.397+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title="20:37 - Osna.FM Live" Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.397+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=149 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.398+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title="20:37 - Osna.FM Live" Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:04 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:04 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"20:37 - Osna.FM Live","artist":"[EN] OSNA.FM | Wave of Osnabrück | Ad-free","album":null,"uri":"https://shoutcast.osna.fm:8800/","trackType":"fm:8800/"} Mar 25 21:38:04 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:38:04 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:04 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:04 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"20:37 - Osna.FM Live","artist":"[EN] OSNA.FM | Wave of Osnabrück | Ad-free","album":null,"uri":"https://shoutcast.osna.fm:8800/","trackType":"fm:8800/"} Mar 25 21:38:04 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:38:04 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:04 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:04 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"20:37 - Osna.FM Live","artist":"[EN] OSNA.FM | Wave of Osnabrück | Ad-free","album":null,"uri":"https://shoutcast.osna.fm:8800/","trackType":"fm:8800/"} Mar 25 21:38:04 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:38:04 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:04 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:04 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"20:37 - Osna.FM Live","artist":"[EN] OSNA.FM | Wave of Osnabrück | Ad-free","album":null,"uri":"https://shoutcast.osna.fm:8800/","trackType":"fm:8800/"} Mar 25 21:38:04 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:38:04 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.412+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=149 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.412+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title="20:37 - Osna.FM Live" Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.412+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=149 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.413+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title="20:37 - Osna.FM Live" Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.413+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=149 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.413+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title="20:37 - Osna.FM Live" Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.413+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=149 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title="20:37 - Osna.FM Live" Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=149 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title="20:37 - Osna.FM Live" Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.415+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=149 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.415+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title="20:37 - Osna.FM Live" Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.415+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=149 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.415+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title="20:37 - Osna.FM Live" Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.416+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=149 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.416+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title="20:37 - Osna.FM Live" Mar 25 21:38:04 volumio volumio[1240]: info: ------------------------------ 36ms Mar 25 21:38:04 volumio volumio[1240]: info: ------------------------------ 35ms Mar 25 21:38:04 volumio volumio[1240]: info: ------------------------------ 36ms Mar 25 21:38:04 volumio volumio[1240]: info: ------------------------------ 34ms Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 28 milliseconds Mar 25 21:38:04 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:04 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:04 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:04 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"20:37 - Osna.FM Live","artist":"[EN] OSNA.FM | Wave of Osnabrück | Ad-free","album":null,"uri":"https://shoutcast.osna.fm:8800/","trackType":"fm:8800/"} Mar 25 21:38:04 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:38:04 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:04 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.429+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=203 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.430+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title="20:37 - Osna.FM Live" Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.430+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=203 volume=39 Mar 25 21:38:04 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:04.430+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=https://shoutcast.osna.fm:8800/ title="20:37 - Osna.FM Live" Mar 25 21:38:04 volumio volumio[1240]: info: ------------------------------ 46ms Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:04 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:05 volumio volumio[1240]: info: Preload queue cleared Mar 25 21:38:05 volumio volumio[1240]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 21:38:05 volumio volumio[1240]: info: CoreStateMachine::ClearQueue Mar 25 21:38:05 volumio volumio[1240]: info: CoreStateMachine::stop Mar 25 21:38:05 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 21:38:05 volumio volumio[1240]: info: CoreStateMachine::stPlaybackTimer Mar 25 21:38:05 volumio volumio[1240]: info: CoreStateMachine::updateTrackBlock Mar 25 21:38:05 volumio volumio[1240]: info: CorePlayQueue::getTrackBlock Mar 25 21:38:05 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:05 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:05 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 21:38:05 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:05 volumio volumio[1240]: info: CoreStateMachine::serviceStop Mar 25 21:38:05 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:05 volumio volumio[1240]: info: CoreCommandRouter::serviceStop Mar 25 21:38:05 volumio volumio[1240]: info: [1774467485357] ControllerWebradio::stop Mar 25 21:38:05 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 21:38:05 volumio volumio[1240]: info: CorePlayQueue::clearPlayQueue Mar 25 21:38:05 volumio volumio[1240]: info: CorePlayQueue::saveQueue Mar 25 21:38:05 volumio volumio[1240]: info: CoreCommandRouter::volumioPushQueue Mar 25 21:38:05 volumio volumio[1240]: info: CoreStateMachine::addQueueItems Mar 25 21:38:05 volumio volumio[1240]: info: CorePlayQueue::addQueueItems Mar 25 21:38:05 volumio volumio[1240]: info: Preload queue cleared Mar 25 21:38:05 volumio volumio[1240]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012 Mar 25 21:38:05 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 25 21:38:05 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:05.358+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_STOPPED positionMs=0 volume=39 Mar 25 21:38:05 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:05.359+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99615939" title="[EN] OSNA.FM | Wave of Osnabrück | Ad-free" Mar 25 21:38:05 volumio volumio[1240]: info: CoreCommandRouter::volumioPushQueue Mar 25 21:38:05 volumio volumio[1240]: info: CorePlayQueue::saveQueue Mar 25 21:38:05 volumio volumio[1240]: info: CoreStateMachine::updateTrackBlock Mar 25 21:38:05 volumio volumio[1240]: info: CorePlayQueue::getTrackBlock Mar 25 21:38:05 volumio volumio[1240]: info: CoreCommandRouter::volumioPlay Mar 25 21:38:05 volumio volumio[1240]: info: CoreStateMachine::play index 0 Mar 25 21:38:05 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 21:38:05 volumio volumio[1240]: info: CoreStateMachine::stop Mar 25 21:38:05 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 21:38:05 volumio volumio[1240]: info: CoreStateMachine::play index undefined Mar 25 21:38:05 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 21:38:05 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:05 volumio volumio[1240]: info: CoreStateMachine::startPlaybackTimer Mar 25 21:38:05 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:05 volumio volumio[1240]: info: [1774467485367] ControllerWebradio::clearAddPlayTrack Mar 25 21:38:05 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 21:38:05 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:05 volumio volumio[1240]: info: Mar 25 21:38:05 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:05 volumio volumio[1240]: info: sendMpdCommand stop took 17 milliseconds Mar 25 21:38:05 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:05 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:05 volumio volumio[1240]: info: sendMpdCommand stop took 7 milliseconds Mar 25 21:38:05 volumio volumio[1240]: info: sendMpdCommand status took 1 milliseconds Mar 25 21:38:05 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 21:38:05 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:05 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:05 volumio volumio[1240]: info: Mar 25 21:38:05 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:05 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:05 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:05 volumio volumio[1240]: info: Mar 25 21:38:05 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:05 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:05 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:05 volumio volumio[1240]: info: Mar 25 21:38:05 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:05 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:05 volumio volumio[1240]: info: sendMpdCommand clear took 1 milliseconds Mar 25 21:38:05 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 25 21:38:05 volumio volumio[1240]: info: sendMpdCommand status took 2 milliseconds Mar 25 21:38:05 volumio volumio[1240]: info: sendMpdCommand status took 1 milliseconds Mar 25 21:38:05 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" Mar 25 21:38:05 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:05 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:05 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:05 volumio volumio[1240]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 25 21:38:05 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:05 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:05 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:05 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:05 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 21:38:05 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:05 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:05 volumio volumio[1240]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 21:38:05 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:05 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:05 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:05 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:05 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:05 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:05 volumio volumio[1240]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 21:38:05 volumio volumio[1240]: info: ------------------------------ 7ms Mar 25 21:38:05 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:05.382+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_STOPPED positionMs=6 volume=39 Mar 25 21:38:05 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:05.382+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_STOPPED positionMs=6 volume=39 Mar 25 21:38:05 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:05.382+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" title="ROCK ANTENNE" Mar 25 21:38:05 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:05.383+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" title="ROCK ANTENNE" Mar 25 21:38:05 volumio volumio[1240]: info: ------------------------------ 9ms Mar 25 21:38:05 volumio volumio[1240]: info: ------------------------------ 8ms Mar 25 21:38:05 volumio volumio[1240]: info: Mar 25 21:38:05 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:05 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:05 volumio volumio[1240]: info: Mar 25 21:38:05 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:05 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:05 volumio volumio[1240]: error: updateQueue error: null Mar 25 21:38:05 volumio volumio[1240]: info: ------------------------------ 10ms Mar 25 21:38:05 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:05 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:05 volumio volumio[1240]: info: Mar 25 21:38:05 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:05 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:05 volumio volumio[1240]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" took 122 milliseconds Mar 25 21:38:05 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 21:38:05 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand play Mar 25 21:38:05 volumio volumio[1240]: info: ------------------------------ 115ms Mar 25 21:38:05 volumio volumio[1240]: info: ------------------------------ 114ms Mar 25 21:38:05 volumio volumio[1240]: info: ------------------------------ 1ms Mar 25 21:38:05 volumio volumio[1240]: info: Mar 25 21:38:05 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:05 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:05 volumio volumio[1240]: info: Mar 25 21:38:05 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:05 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:05 volumio volumio[1240]: info: sendMpdCommand play took 3 milliseconds Mar 25 21:38:05 volumio volumio[1240]: info: ------------------------------ 1ms Mar 25 21:38:05 volumio volumio[1240]: info: ------------------------------ 1ms Mar 25 21:38:06 volumio volumio[1240]: info: Mar 25 21:38:06 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:06 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:06 volumio volumio[1240]: info: Mar 25 21:38:06 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:06 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:06 volumio volumio[1240]: info: Mar 25 21:38:06 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:06 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:06 volumio volumio[1240]: info: sendMpdCommand status took 35 milliseconds Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:06 volumio volumio[1240]: info: Mar 25 21:38:06 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:06 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:06 volumio volumio[1240]: info: Mar 25 21:38:06 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:06 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:06 volumio volumio[1240]: info: sendMpdCommand status took 34 milliseconds Mar 25 21:38:06 volumio volumio[1240]: info: sendMpdCommand status took 33 milliseconds Mar 25 21:38:06 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:06 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:06 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:06 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne","artist":"(#1 - 1986/500000) ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Mar 25 21:38:06 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus stop Mar 25 21:38:06 volumio volumio[1240]: info: ------------------------------ 39ms Mar 25 21:38:06 volumio volumio[1240]: info: Mar 25 21:38:06 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:06 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:06 volumio volumio[1240]: info: sendMpdCommand status took 3 milliseconds Mar 25 21:38:06 volumio volumio[1240]: info: sendMpdCommand status took 3 milliseconds Mar 25 21:38:06 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 25 21:38:06 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:06 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:06 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:06 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne","artist":"(#1 - 1986/500000) ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Mar 25 21:38:06 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:38:06 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:06 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:06 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:06 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne","artist":"(#1 - 1986/500000) ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Mar 25 21:38:06 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:38:06 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.153+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.153+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.153+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://stream.antenne.de:80/rockantenne title="ROCK ANTENNE" Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.153+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://stream.antenne.de:80/rockantenne title="ROCK ANTENNE" Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.154+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.154+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://stream.antenne.de:80/rockantenne title="ROCK ANTENNE" Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.154+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.155+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://stream.antenne.de:80/rockantenne title="ROCK ANTENNE" Mar 25 21:38:06 volumio volumio[1240]: info: ------------------------------ 51ms Mar 25 21:38:06 volumio volumio[1240]: info: ------------------------------ 50ms Mar 25 21:38:06 volumio volumio[1240]: info: Mar 25 21:38:06 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:06 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:06 volumio volumio[1240]: info: sendMpdCommand status took 20 milliseconds Mar 25 21:38:06 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 25 21:38:06 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:06 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:06 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:06 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne","artist":"(#1 - 1986/500000) ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Mar 25 21:38:06 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:38:06 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:06 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:06 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:06 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne","artist":"(#1 - 1986/500000) ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Mar 25 21:38:06 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:38:06 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.175+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.175+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.175+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://stream.antenne.de:80/rockantenne title="ROCK ANTENNE" Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.176+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://stream.antenne.de:80/rockantenne title="ROCK ANTENNE" Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.176+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.176+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://stream.antenne.de:80/rockantenne title="ROCK ANTENNE" Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.176+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=0 volume=39 Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.176+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://stream.antenne.de:80/rockantenne title="ROCK ANTENNE" Mar 25 21:38:06 volumio volumio[1240]: info: ------------------------------ 37ms Mar 25 21:38:06 volumio volumio[1240]: info: ------------------------------ 38ms Mar 25 21:38:06 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:06 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:06 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:06 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:06 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:06 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:06 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:06 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:06 volumio volumio[1240]: info: Mar 25 21:38:06 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:06 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:06 volumio volumio[1240]: info: Mar 25 21:38:06 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:06 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:06 volumio volumio[1240]: info: Mar 25 21:38:06 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:06 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:06 volumio volumio[1240]: info: Mar 25 21:38:06 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:06 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:06 volumio volumio[1240]: info: ------------------------------ 31ms Mar 25 21:38:06 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 28 milliseconds Mar 25 21:38:06 volumio volumio[1240]: info: ------------------------------ 4ms Mar 25 21:38:06 volumio volumio[1240]: info: ------------------------------ 2ms Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:06 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:06 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:06 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fleetwood Mac - Little lies","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Mar 25 21:38:06 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:38:06 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.201+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=70 volume=39 Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.202+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=70 volume=39 Mar 25 21:38:06 volumio volumio[1240]: info: ------------------------------ 59ms Mar 25 21:38:06 volumio volumio[1240]: info: Mar 25 21:38:06 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:06 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:06 volumio volumio[1240]: info: Mar 25 21:38:06 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:06 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:06 volumio volumio[1240]: info: Mar 25 21:38:06 volumio volumio[1240]: ---------------------------- MPD announces system playlist update Mar 25 21:38:06 volumio volumio[1240]: info: Ignoring MPD Status Update Mar 25 21:38:06 volumio volumio[1240]: info: Mar 25 21:38:06 volumio volumio[1240]: ---------------------------- MPD announces state update: player Mar 25 21:38:06 volumio volumio[1240]: info: ControllerMpd::getState Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Mar 25 21:38:06 volumio volumio[1240]: info: ------------------------------ 13ms Mar 25 21:38:06 volumio volumio[1240]: info: sendMpdCommand status took 13 milliseconds Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.207+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://stream.antenne.de:80/rockantenne title="Fleetwood Mac - Little lies" Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.206+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://stream.antenne.de:80/rockantenne title="Fleetwood Mac - Little lies" Mar 25 21:38:06 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:06 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:06 volumio volumio[1240]: info: ------------------------------ 15ms Mar 25 21:38:06 volumio volumio[1240]: info: sendMpdCommand status took 15 milliseconds Mar 25 21:38:06 volumio volumio[1240]: info: ------------------------------ 16ms Mar 25 21:38:06 volumio volumio[1240]: info: sendMpdCommand status took 15 milliseconds Mar 25 21:38:06 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::parseState Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:06 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:06 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:06 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fleetwood Mac - Little lies","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Mar 25 21:38:06 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:38:06 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.227+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=70 volume=39 Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.228+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=70 volume=39 Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.229+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://stream.antenne.de:80/rockantenne title="Fleetwood Mac - Little lies" Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.229+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://stream.antenne.de:80/rockantenne title="Fleetwood Mac - Little lies" Mar 25 21:38:06 volumio volumio[1240]: info: ------------------------------ 36ms Mar 25 21:38:06 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 22 milliseconds Mar 25 21:38:06 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 22 milliseconds Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:06 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Mar 25 21:38:06 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:06 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:06 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fleetwood Mac - Little lies","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Mar 25 21:38:06 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:38:06 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:06 volumio volumio[1240]: info: ControllerMpd::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Mar 25 21:38:06 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Mar 25 21:38:06 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fleetwood Mac - Little lies","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Mar 25 21:38:06 volumio volumio[1240]: verbose: CURRENT POSITION 0 Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play Mar 25 21:38:06 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreStateMachine::pushState Mar 25 21:38:06 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.252+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=117 volume=39 Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.252+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://stream.antenne.de:80/rockantenne title="Fleetwood Mac - Little lies" Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.252+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=117 volume=39 Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.253+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://stream.antenne.de:80/rockantenne title="Fleetwood Mac - Little lies" Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.254+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=117 volume=39 Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.255+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://stream.antenne.de:80/rockantenne title="Fleetwood Mac - Little lies" Mar 25 21:38:06 volumio volumio[1240]: info: ------------------------------ 52ms Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.257+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" state=STATUS_PLAYING positionMs=117 volume=39 Mar 25 21:38:06 volumio volumio[1240]: info: ------------------------------ 52ms Mar 25 21:38:06 volumio volumio5-onboarding[1522]: time=2026-03-25T21:38:06.257+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:41182 @ 0x1b885d0" id=http://stream.antenne.de:80/rockantenne title="Fleetwood Mac - Little lies" Mar 25 21:38:06 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:06 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:06 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:06 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:06 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:06 volumio volumio[1240]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 25 21:38:06 volumio volumio[1240]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 21:38:06 volumio volumio[1240]: AggregateError Mar 25 21:38:06 volumio volumio[1240]: at internalConnectMultiple (node:net:1114:18) Mar 25 21:38:06 volumio volumio[1240]: at afterConnectMultiple (node:net:1667:5) { Mar 25 21:38:06 volumio volumio[1240]: code: 'EHOSTUNREACH', Mar 25 21:38:06 volumio volumio[1240]: [errors]: [ Mar 25 21:38:06 volumio volumio[1240]: Error: connect EHOSTUNREACH 212.39.68.40:443 Mar 25 21:38:06 volumio volumio[1240]: at createConnectionError (node:net:1634:14) Mar 25 21:38:06 volumio volumio[1240]: at afterConnectMultiple (node:net:1664:40) { Mar 25 21:38:06 volumio volumio[1240]: errno: -113, Mar 25 21:38:06 volumio volumio[1240]: code: 'EHOSTUNREACH', Mar 25 21:38:06 volumio volumio[1240]: syscall: 'connect', Mar 25 21:38:06 volumio volumio[1240]: address: '212.39.68.40', Mar 25 21:38:06 volumio volumio[1240]: port: 443 Mar 25 21:38:06 volumio volumio[1240]: }, Mar 25 21:38:06 volumio volumio[1240]: Error: connect EHOSTUNREACH 212.39.68.48:443 Mar 25 21:38:06 volumio volumio[1240]: at createConnectionError (node:net:1634:14) Mar 25 21:38:06 volumio volumio[1240]: at afterConnectMultiple (node:net:1664:40) { Mar 25 21:38:06 volumio volumio[1240]: errno: -113, Mar 25 21:38:06 volumio volumio[1240]: code: 'EHOSTUNREACH', Mar 25 21:38:06 volumio volumio[1240]: syscall: 'connect', Mar 25 21:38:06 volumio volumio[1240]: address: '212.39.68.48', Mar 25 21:38:06 volumio volumio[1240]: port: 443 Mar 25 21:38:06 volumio volumio[1240]: } Mar 25 21:38:06 volumio volumio[1240]: ] Mar 25 21:38:06 volumio volumio[1240]: } Mar 25 21:38:06 volumio volumio[1240]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 21:38:07 volumio sudo[14647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-25 21:37' Mar 25 21:38:07 volumio sudo[14647]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"