-- Logs begin at Fri 2026-03-27 23:08:11 CET, end at Sat 2026-03-28 11:01:21 CET. -- Mar 28 11:00:06 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 28 11:00:07 bar48 volumio[825]: info: Preload queue cleared Mar 28 11:00:24 bar48 volumio[825]: info: Mar 28 11:00:24 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:24 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:24 bar48 volumio[825]: info: Mar 28 11:00:24 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:24 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:24 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:24 bar48 volumio[825]: info: Mar 28 11:00:24 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:24 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:24 bar48 volumio[825]: info: Mar 28 11:00:24 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:24 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:24 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:24 bar48 volumio[825]: info: Mar 28 11:00:24 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:24 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:24 bar48 volumio[825]: info: Mar 28 11:00:24 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:24 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:24 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:24 bar48 volumio[825]: info: ------------------------------ 9ms Mar 28 11:00:24 bar48 volumio[825]: info: sendMpdCommand status took 8 milliseconds Mar 28 11:00:24 bar48 volumio[825]: info: ------------------------------ 7ms Mar 28 11:00:24 bar48 volumio[825]: info: sendMpdCommand status took 7 milliseconds Mar 28 11:00:24 bar48 volumio[825]: info: ------------------------------ 6ms Mar 28 11:00:24 bar48 volumio[825]: info: sendMpdCommand status took 5 milliseconds Mar 28 11:00:24 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:24 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:24 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:24 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:24 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:24 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:24 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 11:00:24 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 11:00:24 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 11:00:24 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:24 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:24 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:24 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:24 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:24 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:24 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":39058,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Spinning the 78s - Arctic Outpost AM1270","artist":"Arctic Outpost AM1270","album":null,"uri":"http://216.126.196.154:4047/stream","trackType":""} Mar 28 11:00:24 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:24 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:24 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:24 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:24 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:24 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:24 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 11:00:24 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:24 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:24 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:24 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:24 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:24 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:24 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":39058,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Spinning the 78s - Arctic Outpost AM1270","artist":"Arctic Outpost AM1270","album":null,"uri":"http://216.126.196.154:4047/stream","trackType":""} Mar 28 11:00:24 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:24 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:24 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:24 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:24 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:24 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:24 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:24 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:24 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:24 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:24 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:24 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:24 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":39058,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Spinning the 78s - Arctic Outpost AM1270","artist":"Arctic Outpost AM1270","album":null,"uri":"http://216.126.196.154:4047/stream","trackType":""} Mar 28 11:00:24 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:24 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:24 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:24 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:24 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:24 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:24 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:24 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:24 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:24 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:24.073+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=38316 volume=100 Mar 28 11:00:24 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:24.073+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://216.126.196.154:4047/stream title="Spinning the 78s - Arctic Outpost AM1270" Mar 28 11:00:24 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:24.075+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=38316 volume=100 Mar 28 11:00:24 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:24.075+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://216.126.196.154:4047/stream title="Spinning the 78s - Arctic Outpost AM1270" Mar 28 11:00:24 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:24.077+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=38316 volume=100 Mar 28 11:00:24 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:24.078+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://216.126.196.154:4047/stream title="Spinning the 78s - Arctic Outpost AM1270" Mar 28 11:00:24 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:24.080+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=38316 volume=100 Mar 28 11:00:24 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:24.080+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://216.126.196.154:4047/stream title="Spinning the 78s - Arctic Outpost AM1270" Mar 28 11:00:24 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:24.082+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=38316 volume=100 Mar 28 11:00:24 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:24.083+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://216.126.196.154:4047/stream title="Spinning the 78s - Arctic Outpost AM1270" Mar 28 11:00:24 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:24.085+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=38316 volume=100 Mar 28 11:00:24 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:24.085+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://216.126.196.154:4047/stream title="Spinning the 78s - Arctic Outpost AM1270" Mar 28 11:00:24 bar48 volumio[825]: info: ------------------------------ 89ms Mar 28 11:00:24 bar48 volumio[825]: info: ------------------------------ 87ms Mar 28 11:00:24 bar48 volumio[825]: info: ------------------------------ 86ms Mar 28 11:00:34 bar48 volumio[825]: info: Mar 28 11:00:34 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:34 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:34 bar48 volumio[825]: info: Mar 28 11:00:34 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:34 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:34 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:34 bar48 volumio[825]: info: Mar 28 11:00:34 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:34 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:34 bar48 volumio[825]: info: Mar 28 11:00:34 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:34 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:34 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:34 bar48 volumio[825]: info: Mar 28 11:00:34 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:34 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:34 bar48 volumio[825]: info: Mar 28 11:00:34 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:34 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:34 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:34 bar48 volumio[825]: info: ------------------------------ 8ms Mar 28 11:00:34 bar48 volumio[825]: info: sendMpdCommand status took 7 milliseconds Mar 28 11:00:34 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:34 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:34 bar48 volumio[825]: info: ------------------------------ 9ms Mar 28 11:00:34 bar48 volumio[825]: info: sendMpdCommand status took 8 milliseconds Mar 28 11:00:34 bar48 volumio[825]: info: ------------------------------ 8ms Mar 28 11:00:34 bar48 volumio[825]: info: sendMpdCommand status took 6 milliseconds Mar 28 11:00:34 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 28 11:00:34 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:34 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:34 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:34 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:34 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:34 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:34 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:34 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:34 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49367,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"See You In Hell, Blind Boy - Ry Cooder","artist":"Arctic Outpost AM1270","album":null,"uri":"http://216.126.196.154:4047/stream","trackType":""} Mar 28 11:00:34 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:34 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:34 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:34 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:34 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:34 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:34 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 11:00:34 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:34 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:34 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:34 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:34.338+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=48580 volume=100 Mar 28 11:00:34 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:34.339+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://216.126.196.154:4047/stream title="See You In Hell, Blind Boy - Ry Cooder" Mar 28 11:00:34 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:34.340+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=48580 volume=100 Mar 28 11:00:34 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:34.341+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://216.126.196.154:4047/stream title="See You In Hell, Blind Boy - Ry Cooder" Mar 28 11:00:34 bar48 volumio[825]: info: ------------------------------ 40ms Mar 28 11:00:34 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 29 milliseconds Mar 28 11:00:34 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 28 milliseconds Mar 28 11:00:34 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:34 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:34 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:34 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:34 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:34 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49367,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"See You In Hell, Blind Boy - Ry Cooder","artist":"Arctic Outpost AM1270","album":null,"uri":"http://216.126.196.154:4047/stream","trackType":""} Mar 28 11:00:34 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:34 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:34 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:34 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:34 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:34 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:34 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:34 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:34 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:34 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:34 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:34 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:34 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49367,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"See You In Hell, Blind Boy - Ry Cooder","artist":"Arctic Outpost AM1270","album":null,"uri":"http://216.126.196.154:4047/stream","trackType":""} Mar 28 11:00:34 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:34 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:34 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:34 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:34 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:34 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:34 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:34 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:34 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:34 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:34.381+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=48580 volume=100 Mar 28 11:00:34 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:34.382+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://216.126.196.154:4047/stream title="See You In Hell, Blind Boy - Ry Cooder" Mar 28 11:00:34 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:34.382+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=48580 volume=100 Mar 28 11:00:34 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:34.384+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://216.126.196.154:4047/stream title="See You In Hell, Blind Boy - Ry Cooder" Mar 28 11:00:34 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:34.385+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=48580 volume=100 Mar 28 11:00:34 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:34.386+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://216.126.196.154:4047/stream title="See You In Hell, Blind Boy - Ry Cooder" Mar 28 11:00:34 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:34.388+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=48580 volume=100 Mar 28 11:00:34 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:34.388+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://216.126.196.154:4047/stream title="See You In Hell, Blind Boy - Ry Cooder" Mar 28 11:00:34 bar48 volumio[825]: info: ------------------------------ 92ms Mar 28 11:00:34 bar48 volumio[825]: info: ------------------------------ 90ms Mar 28 11:00:36 bar48 volumio[825]: info: Saving local item radio-favourites Mar 28 11:00:36 bar48 volumio[825]: info: Pushing Favourites {"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99623625","favourite":false} Mar 28 11:00:48 bar48 volumio[825]: info: Preload queue cleared Mar 28 11:00:48 bar48 volumio[825]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::ClearQueue Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::stop Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::stPlaybackTimer Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::updateTrackBlock Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::getTrackBlock Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:48 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 11:00:48 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::serviceStop Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:48 bar48 volumio[825]: info: CoreCommandRouter::serviceStop Mar 28 11:00:48 bar48 volumio[825]: info: [1774692048128] ControllerWebradio::stop Mar 28 11:00:48 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::clearPlayQueue Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::saveQueue Mar 28 11:00:48 bar48 volumio[825]: info: CoreCommandRouter::volumioPushQueue Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::addQueueItems Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::addQueueItems Mar 28 11:00:48 bar48 volumio[825]: info: Preload queue cleared Mar 28 11:00:48 bar48 volumio[825]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99612225 Mar 28 11:00:48 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 28 11:00:48 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:48.136+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 11:00:48 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:48.136+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99527295" title="Arctic Outpost AM1270" Mar 28 11:00:48 bar48 volumio[825]: info: CoreCommandRouter::volumioPushQueue Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::saveQueue Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::updateTrackBlock Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::getTrackBlock Mar 28 11:00:48 bar48 volumio[825]: info: CoreCommandRouter::volumioPlay Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::play index 0 Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::stop Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::play index undefined Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::startPlaybackTimer Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:48 bar48 volumio[825]: info: [1774692048155] ControllerWebradio::clearAddPlayTrack Mar 28 11:00:48 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 11:00:48 bar48 volumio[825]: info: Mar 28 11:00:48 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:48 bar48 volumio[825]: info: sendMpdCommand stop took 31 milliseconds Mar 28 11:00:48 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:48 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:48 bar48 volumio[825]: info: sendMpdCommand stop took 4 milliseconds Mar 28 11:00:48 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 11:00:48 bar48 volumio[825]: info: Mar 28 11:00:48 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:48 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:48 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:48 bar48 volumio[825]: info: Mar 28 11:00:48 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:48 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:48 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:48 bar48 volumio[825]: info: Mar 28 11:00:48 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:48 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:48 bar48 volumio[825]: info: sendMpdCommand status took 5 milliseconds Mar 28 11:00:48 bar48 volumio[825]: info: sendMpdCommand clear took 5 milliseconds Mar 28 11:00:48 bar48 volumio[825]: info: sendMpdCommand status took 4 milliseconds Mar 28 11:00:48 bar48 volumio[825]: info: sendMpdCommand status took 3 milliseconds Mar 28 11:00:48 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:48 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:48 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99612225" Mar 28 11:00:48 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:48 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:48 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:48 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:48 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 11:00:48 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:48 bar48 volumio[825]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 28 11:00:48 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:48 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:48 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:48 bar48 volumio[825]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 28 11:00:48 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:48.204+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 11:00:48 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:48.206+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99612225" title="radio neptunus sneek" Mar 28 11:00:48 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:48.207+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 11:00:48 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:48.209+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99612225" title="radio neptunus sneek" Mar 28 11:00:48 bar48 volumio[825]: info: ------------------------------ 56ms Mar 28 11:00:48 bar48 volumio[825]: info: ------------------------------ 56ms Mar 28 11:00:48 bar48 volumio[825]: info: Mar 28 11:00:48 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:48 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:48 bar48 volumio[825]: info: Mar 28 11:00:48 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:48 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:48 bar48 volumio[825]: error: updateQueue error: null Mar 28 11:00:48 bar48 volumio[825]: info: ------------------------------ 60ms Mar 28 11:00:48 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 58 milliseconds Mar 28 11:00:48 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:48 bar48 volumio[825]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 28 11:00:48 bar48 volumio[825]: info: ------------------------------ 68ms Mar 28 11:00:48 bar48 volumio[825]: info: Preload queue cleared Mar 28 11:00:48 bar48 volumio[825]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::ClearQueue Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::stop Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::clearPlayQueue Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::saveQueue Mar 28 11:00:48 bar48 volumio[825]: info: CoreCommandRouter::volumioPushQueue Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::addQueueItems Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::addQueueItems Mar 28 11:00:48 bar48 volumio[825]: info: Preload queue cleared Mar 28 11:00:48 bar48 volumio[825]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99612225 Mar 28 11:00:48 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 28 11:00:48 bar48 volumio[825]: info: CoreCommandRouter::volumioPushQueue Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::saveQueue Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::updateTrackBlock Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::getTrackBlock Mar 28 11:00:48 bar48 volumio[825]: info: CoreCommandRouter::volumioPlay Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::play index 0 Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::stop Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::play index undefined Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:48 bar48 volumio[825]: info: CoreStateMachine::startPlaybackTimer Mar 28 11:00:48 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:48 bar48 volumio[825]: info: [1774692048464] ControllerWebradio::clearAddPlayTrack Mar 28 11:00:48 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99612225" took 1102 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand play Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:49 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 1051ms Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 1050ms Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand stop took 806 milliseconds Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:49 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:49 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:49 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:49 bar48 volumio[825]: error: updateQueue error: null Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand play took 8 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 9ms Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand clear took 6 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 6ms Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99612225" Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:49 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:49 bar48 volumio[825]: error: updateQueue error: null Mar 28 11:00:49 bar48 volumio[825]: error: updateQueue error: null Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 10ms Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 8ms Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand status took 6 milliseconds Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:49 bar48 volumio[825]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 11:00:49 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState stateService stop Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus stop Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: No code Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.326+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=759 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.329+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://216.126.196.154:4047/stream title="See You In Hell, Blind Boy - Ry Cooder" Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.331+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=759 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.332+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://216.126.196.154:4047/stream title="See You In Hell, Blind Boy - Ry Cooder" Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.334+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=759 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.335+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://216.126.196.154:4047/stream title="See You In Hell, Blind Boy - Ry Cooder" Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 66ms Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:49 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99612225" took 95 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand play Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:49 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 97ms Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand status took 95 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 33ms Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand status took 31 milliseconds Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:49 bar48 volumio[825]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 11:00:49 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState stateService stop Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus stop Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: No code Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:49 bar48 volumio[825]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 11:00:49 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState stateService stop Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus stop Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: No code Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.418+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=759 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.418+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://216.126.196.154:4047/stream title="See You In Hell, Blind Boy - Ry Cooder" Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.420+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=759 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.421+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://216.126.196.154:4047/stream title="See You In Hell, Blind Boy - Ry Cooder" Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.423+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=759 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.423+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://216.126.196.154:4047/stream title="See You In Hell, Blind Boy - Ry Cooder" Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.426+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=759 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.426+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://216.126.196.154:4047/stream title="See You In Hell, Blind Boy - Ry Cooder" Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.429+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=759 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.430+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=759 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.431+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://216.126.196.154:4047/stream title="See You In Hell, Blind Boy - Ry Cooder" Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 166ms Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 103ms Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:49 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:49 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand play took 82 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 81ms Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 4ms Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 4ms Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand status took 3 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand status took 10 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand status took 6 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand status took 4 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand status took 3 milliseconds Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:49 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:49 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 0/500) radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:49 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus stop Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 20ms Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand status took 4 milliseconds Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:49 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:49 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 0/500) radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:49 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:49 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:49 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:49 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 0/500) radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:49 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:49 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:49 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:49 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 0/500) radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:49 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:49 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:49 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:49 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 0/500) radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:49 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:49 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.569+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.570+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title= Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.573+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.573+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title= Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.575+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.575+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title= Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.577+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.577+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title= Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.579+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.579+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title= Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.580+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.581+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title= Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.583+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.584+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title= Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.586+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.587+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title= Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 131ms Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 130ms Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 126ms Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 125ms Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:49 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 111 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:49 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:49 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 0/500) radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:49 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:49 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.640+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.641+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title= Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.642+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.643+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title= Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 146ms Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:49 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:49 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:49 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 51ms Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand status took 49 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 9ms Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand status took 8 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 7ms Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand status took 6 milliseconds Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:49 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:49 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:49 bar48 volumio[825]: info: Mar 28 11:00:49 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 13ms Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand status took 10 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 7ms Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand status took 5 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 5ms Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand status took 4 milliseconds Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:49 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:49 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::","artist":"radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:49 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:49 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:49 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:49 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::","artist":"radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:49 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:49 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:49 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:49 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::","artist":"radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:49 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:49 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.728+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.728+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.729+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.730+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.732+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.733+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.736+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.737+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.738+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.739+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.740+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.740+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 140ms Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 98ms Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 97ms Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 82 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 80 milliseconds Mar 28 11:00:49 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 80 milliseconds Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:49 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:49 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:49 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::","artist":"radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:49 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:49 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:49 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:49 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::","artist":"radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:49 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:49 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:49 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:49 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::","artist":"radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:49 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:49 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:49 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.808+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=289 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.809+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.811+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=289 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.812+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=289 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.813+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.814+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.818+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=289 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.819+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=289 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.819+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=289 volume=100 Mar 28 11:00:49 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:49.820+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 169ms Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 162ms Mar 28 11:00:49 bar48 volumio[825]: info: ------------------------------ 161ms Mar 28 11:00:51 bar48 volumio[825]: info: Preload queue cleared Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::ClearQueue Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::stop Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::stPlaybackTimer Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::updateTrackBlock Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrackBlock Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::serviceStop Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::serviceStop Mar 28 11:00:51 bar48 volumio[825]: info: [1774692051327] ControllerWebradio::stop Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::clearPlayQueue Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::saveQueue Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushQueue Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::addQueueItems Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::addQueueItems Mar 28 11:00:51 bar48 volumio[825]: info: Preload queue cleared Mar 28 11:00:51 bar48 volumio[825]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99612225 Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.334+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.335+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99612225" title="radio neptunus sneek" Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushQueue Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::saveQueue Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::updateTrackBlock Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrackBlock Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPlay Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::play index 0 Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::stop Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::play index undefined Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::startPlaybackTimer Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:51 bar48 volumio[825]: info: [1774692051351] ControllerWebradio::clearAddPlayTrack Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand stop took 27 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand stop took 5 milliseconds Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:51 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand status took 8 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand clear took 9 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand status took 7 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand status took 6 milliseconds Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99612225" Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:51 bar48 volumio[825]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:51 bar48 volumio[825]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.385+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.386+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99612225" title="radio neptunus sneek" Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.387+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.387+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99612225" title="radio neptunus sneek" Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 35ms Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 34ms Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:51 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:51 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:51 bar48 volumio[825]: error: updateQueue error: null Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 37ms Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 31 milliseconds Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:51 bar48 volumio[825]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 43ms Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99612225" took 97 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand play Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:51 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 73ms Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 73ms Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand play took 3 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:51 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:51 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 5ms Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 4ms Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 3ms Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand status took 3 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand status took 14 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand status took 12 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand status took 11 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand status took 9 milliseconds Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:51 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 0/500) radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:51 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus stop Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 23ms Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:51 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 0/500) radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:51 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:51 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:51 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 0/500) radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:51 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:51 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:51 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 0/500) radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:51 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:51 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:51 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 0/500) radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:51 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:51 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.679+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.680+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title= Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.680+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.681+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title= Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.683+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.683+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title= Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.687+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.687+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title= Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.692+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.692+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title= Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.695+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.696+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title= Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.697+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.699+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title= Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.700+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.703+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title= Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 129ms Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 127ms Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 124ms Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 124ms Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:51 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand status took 111 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:51 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:51 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:51 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 20ms Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand status took 20 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 8ms Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand status took 6 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 5ms Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand status took 5 milliseconds Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:51 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::","artist":"radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:51 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:51 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.772+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.773+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.774+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.775+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 162ms Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:51 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:00:51 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:00:51 bar48 volumio[825]: info: Mar 28 11:00:51 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 40ms Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand status took 38 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 36 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 36 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 35 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 7ms Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand status took 6 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 6ms Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand status took 5 milliseconds Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:51 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::","artist":"radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:51 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:51 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:51 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::","artist":"radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:51 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:51 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:51 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::","artist":"radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:51 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:51 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.834+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=180 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.835+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.836+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=180 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.837+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.839+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=180 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.840+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.840+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=180 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.841+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.843+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=180 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.845+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=180 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.845+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 137ms Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 124ms Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 123ms Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 88 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 86 milliseconds Mar 28 11:00:51 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 86 milliseconds Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:51 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:51 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::","artist":"radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:51 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:51 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:51 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::","artist":"radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:51 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:51 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:00:51 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:00:51 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::","artist":"radio neptunus sneek","album":null,"uri":"http://84.105.11.223:7830/","trackType":"223:7830/"} Mar 28 11:00:51 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:00:51 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:00:51 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.921+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=272 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.921+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.925+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=272 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.925+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.928+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=272 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.930+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=272 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.931+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.932+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=272 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.933+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.935+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=272 volume=100 Mar 28 11:00:51 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:00:51.935+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://84.105.11.223:7830/ title="Keener – Shelter – Epidemic21 ::You make your choice Hardcore Radio Sneek::" Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 205ms Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 170ms Mar 28 11:00:51 bar48 volumio[825]: info: ------------------------------ 169ms Mar 28 11:01:10 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 28 11:01:10 bar48 volumio[825]: info: Preload queue cleared Mar 28 11:01:19 bar48 volumio[825]: info: Preload queue cleared Mar 28 11:01:19 bar48 volumio[825]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 11:01:19 bar48 volumio[825]: info: CoreStateMachine::ClearQueue Mar 28 11:01:19 bar48 volumio[825]: info: CoreStateMachine::stop Mar 28 11:01:19 bar48 volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 11:01:19 bar48 volumio[825]: info: CoreStateMachine::stPlaybackTimer Mar 28 11:01:19 bar48 volumio[825]: info: CoreStateMachine::updateTrackBlock Mar 28 11:01:19 bar48 volumio[825]: info: CorePlayQueue::getTrackBlock Mar 28 11:01:19 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:01:19 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:01:19 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 11:01:19 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:01:19 bar48 volumio[825]: info: CoreStateMachine::serviceStop Mar 28 11:01:19 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:01:19 bar48 volumio[825]: info: CoreCommandRouter::serviceStop Mar 28 11:01:19 bar48 volumio[825]: info: [1774692079786] ControllerWebradio::stop Mar 28 11:01:19 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 11:01:19 bar48 volumio[825]: info: CorePlayQueue::clearPlayQueue Mar 28 11:01:19 bar48 volumio[825]: info: CorePlayQueue::saveQueue Mar 28 11:01:19 bar48 volumio[825]: info: CoreCommandRouter::volumioPushQueue Mar 28 11:01:19 bar48 volumio[825]: info: CoreStateMachine::addQueueItems Mar 28 11:01:19 bar48 volumio[825]: info: CorePlayQueue::addQueueItems Mar 28 11:01:19 bar48 volumio[825]: info: Preload queue cleared Mar 28 11:01:19 bar48 volumio[825]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99601488 Mar 28 11:01:19 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 28 11:01:19 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:19.793+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 11:01:19 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:19.793+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99612225" title="radio neptunus sneek" Mar 28 11:01:19 bar48 volumio[825]: info: CoreCommandRouter::volumioPushQueue Mar 28 11:01:19 bar48 volumio[825]: info: CorePlayQueue::saveQueue Mar 28 11:01:19 bar48 volumio[825]: info: CoreStateMachine::updateTrackBlock Mar 28 11:01:19 bar48 volumio[825]: info: CorePlayQueue::getTrackBlock Mar 28 11:01:19 bar48 volumio[825]: info: CoreCommandRouter::volumioPlay Mar 28 11:01:19 bar48 volumio[825]: info: CoreStateMachine::play index 0 Mar 28 11:01:19 bar48 volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 11:01:19 bar48 volumio[825]: info: CoreStateMachine::stop Mar 28 11:01:19 bar48 volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 11:01:19 bar48 volumio[825]: info: CoreStateMachine::play index undefined Mar 28 11:01:19 bar48 volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 11:01:19 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:01:19 bar48 volumio[825]: info: CoreStateMachine::startPlaybackTimer Mar 28 11:01:19 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:01:19 bar48 volumio[825]: info: [1774692079808] ControllerWebradio::clearAddPlayTrack Mar 28 11:01:19 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 11:01:19 bar48 volumio[825]: info: Mar 28 11:01:19 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:01:19 bar48 volumio[825]: info: sendMpdCommand stop took 26 milliseconds Mar 28 11:01:19 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:01:19 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:01:19 bar48 volumio[825]: info: sendMpdCommand stop took 5 milliseconds Mar 28 11:01:19 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 11:01:19 bar48 volumio[825]: info: Mar 28 11:01:19 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:01:19 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:01:19 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:01:19 bar48 volumio[825]: info: Mar 28 11:01:19 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:01:19 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:01:19 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:01:19 bar48 volumio[825]: info: Mar 28 11:01:19 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:01:19 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:01:19 bar48 volumio[825]: info: sendMpdCommand status took 10 milliseconds Mar 28 11:01:19 bar48 volumio[825]: info: sendMpdCommand clear took 10 milliseconds Mar 28 11:01:19 bar48 volumio[825]: info: sendMpdCommand status took 9 milliseconds Mar 28 11:01:19 bar48 volumio[825]: info: sendMpdCommand status took 8 milliseconds Mar 28 11:01:19 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:01:19 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:01:19 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99601488" Mar 28 11:01:19 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:01:19 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:01:19 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:01:19 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:01:19 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:01:19 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:01:19 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 11:01:19 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:01:19 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:01:19 bar48 volumio[825]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 28 11:01:19 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:01:19 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:01:19 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:01:19 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:01:19 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:01:19 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:01:19 bar48 volumio[825]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 28 11:01:19 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:19.844+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 11:01:19 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:19.844+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99601488" title="Alpo Radio" Mar 28 11:01:19 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:19.846+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 11:01:19 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:19.846+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99601488" title="Alpo Radio" Mar 28 11:01:19 bar48 volumio[825]: info: ------------------------------ 37ms Mar 28 11:01:19 bar48 volumio[825]: info: ------------------------------ 37ms Mar 28 11:01:19 bar48 volumio[825]: info: Mar 28 11:01:19 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:01:19 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:01:19 bar48 volumio[825]: info: Mar 28 11:01:19 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:01:19 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:01:19 bar48 volumio[825]: error: updateQueue error: null Mar 28 11:01:19 bar48 volumio[825]: info: ------------------------------ 40ms Mar 28 11:01:19 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 32 milliseconds Mar 28 11:01:19 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:01:19 bar48 volumio[825]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 28 11:01:19 bar48 volumio[825]: info: ------------------------------ 46ms Mar 28 11:01:19 bar48 volumio[825]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99601488" took 96 milliseconds Mar 28 11:01:19 bar48 volumio[825]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 28 11:01:19 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand play Mar 28 11:01:19 bar48 volumio[825]: info: Mar 28 11:01:19 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:01:19 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:01:19 bar48 volumio[825]: info: ------------------------------ 71ms Mar 28 11:01:19 bar48 volumio[825]: info: ------------------------------ 71ms Mar 28 11:01:19 bar48 volumio[825]: info: sendMpdCommand play took 3 milliseconds Mar 28 11:01:19 bar48 volumio[825]: info: Mar 28 11:01:19 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:01:19 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:01:19 bar48 volumio[825]: info: Mar 28 11:01:19 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:01:19 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:01:19 bar48 volumio[825]: info: ------------------------------ 7ms Mar 28 11:01:19 bar48 volumio[825]: info: ------------------------------ 4ms Mar 28 11:01:19 bar48 volumio[825]: info: ------------------------------ 3ms Mar 28 11:01:20 bar48 volumio[825]: info: Mar 28 11:01:20 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:01:20 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:01:20 bar48 volumio[825]: info: Mar 28 11:01:20 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:01:20 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:01:20 bar48 volumio[825]: info: Mar 28 11:01:20 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:01:20 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:01:20 bar48 volumio[825]: info: sendMpdCommand status took 4 milliseconds Mar 28 11:01:20 bar48 volumio[825]: info: sendMpdCommand status took 4 milliseconds Mar 28 11:01:20 bar48 volumio[825]: info: sendMpdCommand status took 2 milliseconds Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:01:20 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 28 11:01:20 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 28 11:01:20 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:01:20 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:01:20 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:01:20 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 100/500) Alpo Radio","album":null,"uri":"http://95.217.32.113:8001/stream","trackType":""} Mar 28 11:01:20 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus stop Mar 28 11:01:20 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:01:20 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:01:20 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 100/500) Alpo Radio","album":null,"uri":"http://95.217.32.113:8001/stream","trackType":""} Mar 28 11:01:20 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:01:20 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:01:20 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:01:20 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:01:20 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 100/500) Alpo Radio","album":null,"uri":"http://95.217.32.113:8001/stream","trackType":""} Mar 28 11:01:20 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:01:20 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:01:20 bar48 volumio[825]: info: ------------------------------ 37ms Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.243+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.244+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://95.217.32.113:8001/stream title="Alpo Radio" Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.246+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.246+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://95.217.32.113:8001/stream title="Alpo Radio" Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.248+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.249+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://95.217.32.113:8001/stream title="Alpo Radio" Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.251+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.252+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://95.217.32.113:8001/stream title="Alpo Radio" Mar 28 11:01:20 bar48 volumio[825]: info: ------------------------------ 75ms Mar 28 11:01:20 bar48 volumio[825]: info: ------------------------------ 75ms Mar 28 11:01:20 bar48 volumio[825]: info: Mar 28 11:01:20 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:01:20 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:01:20 bar48 volumio[825]: info: Mar 28 11:01:20 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:01:20 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:01:20 bar48 volumio[825]: info: Mar 28 11:01:20 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:01:20 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:01:20 bar48 volumio[825]: info: Mar 28 11:01:20 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:01:20 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:01:20 bar48 volumio[825]: info: Mar 28 11:01:20 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:01:20 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:01:20 bar48 volumio[825]: info: Mar 28 11:01:20 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:01:20 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:01:20 bar48 volumio[825]: info: Mar 28 11:01:20 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:01:20 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:01:20 bar48 volumio[825]: info: Mar 28 11:01:20 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:01:20 bar48 volumio[825]: info: ------------------------------ 9ms Mar 28 11:01:20 bar48 volumio[825]: info: sendMpdCommand status took 9 milliseconds Mar 28 11:01:20 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:01:20 bar48 volumio[825]: info: ------------------------------ 8ms Mar 28 11:01:20 bar48 volumio[825]: info: sendMpdCommand status took 8 milliseconds Mar 28 11:01:20 bar48 volumio[825]: info: ------------------------------ 7ms Mar 28 11:01:20 bar48 volumio[825]: info: sendMpdCommand status took 6 milliseconds Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:01:20 bar48 volumio[825]: info: Mar 28 11:01:20 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:01:20 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:01:20 bar48 volumio[825]: info: Mar 28 11:01:20 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:01:20 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:01:20 bar48 volumio[825]: info: Mar 28 11:01:20 bar48 volumio[825]: ---------------------------- MPD announces system playlist update Mar 28 11:01:20 bar48 volumio[825]: info: Ignoring MPD Status Update Mar 28 11:01:20 bar48 volumio[825]: info: Mar 28 11:01:20 bar48 volumio[825]: ---------------------------- MPD announces state update: player Mar 28 11:01:20 bar48 volumio[825]: info: ControllerMpd::getState Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand status Mar 28 11:01:20 bar48 volumio[825]: info: ------------------------------ 11ms Mar 28 11:01:20 bar48 volumio[825]: info: sendMpdCommand status took 9 milliseconds Mar 28 11:01:20 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 28 11:01:20 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 28 11:01:20 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 28 11:01:20 bar48 volumio[825]: info: ------------------------------ 6ms Mar 28 11:01:20 bar48 volumio[825]: info: sendMpdCommand status took 6 milliseconds Mar 28 11:01:20 bar48 volumio[825]: info: ------------------------------ 5ms Mar 28 11:01:20 bar48 volumio[825]: info: sendMpdCommand status took 4 milliseconds Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::parseState Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 11:01:20 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:01:20 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:01:20 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":228,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Capital T - Kujtime (Prod. BO Beatz) (1)","artist":"Alpo Radio","album":null,"uri":"http://95.217.32.113:8001/stream","trackType":""} Mar 28 11:01:20 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:01:20 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:01:20 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:01:20 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:01:20 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Capital T - Kujtime (Prod. BO Beatz) (1)","artist":"Alpo Radio","album":null,"uri":"http://95.217.32.113:8001/stream","trackType":""} Mar 28 11:01:20 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:01:20 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:01:20 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:01:20 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:01:20 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Capital T - Kujtime (Prod. BO Beatz) (1)","artist":"Alpo Radio","album":null,"uri":"http://95.217.32.113:8001/stream","trackType":""} Mar 28 11:01:20 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:01:20 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.432+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=210 volume=100 Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.433+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://95.217.32.113:8001/stream title="Capital T - Kujtime (Prod. BO Beatz) (1)" Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.443+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=210 volume=100 Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.443+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://95.217.32.113:8001/stream title="Capital T - Kujtime (Prod. BO Beatz) (1)" Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.443+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=210 volume=100 Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.444+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://95.217.32.113:8001/stream title="Capital T - Kujtime (Prod. BO Beatz) (1)" Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.445+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=210 volume=100 Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.447+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://95.217.32.113:8001/stream title="Capital T - Kujtime (Prod. BO Beatz) (1)" Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.449+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=210 volume=100 Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.450+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://95.217.32.113:8001/stream title="Capital T - Kujtime (Prod. BO Beatz) (1)" Mar 28 11:01:20 bar48 volumio[825]: info: ------------------------------ 101ms Mar 28 11:01:20 bar48 volumio[825]: info: ------------------------------ 100ms Mar 28 11:01:20 bar48 volumio[825]: info: ------------------------------ 100ms Mar 28 11:01:20 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 85 milliseconds Mar 28 11:01:20 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 85 milliseconds Mar 28 11:01:20 bar48 volumio[825]: info: sendMpdCommand playlistinfo took 84 milliseconds Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:01:20 bar48 volumio[825]: verbose: ControllerMpd::parseTrackInfo Mar 28 11:01:20 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:01:20 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:01:20 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Capital T - Kujtime (Prod. BO Beatz) (1)","artist":"Alpo Radio","album":null,"uri":"http://95.217.32.113:8001/stream","trackType":""} Mar 28 11:01:20 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:01:20 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:01:20 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:01:20 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:01:20 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Capital T - Kujtime (Prod. BO Beatz) (1)","artist":"Alpo Radio","album":null,"uri":"http://95.217.32.113:8001/stream","trackType":""} Mar 28 11:01:20 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:01:20 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:01:20 bar48 volumio[825]: info: ControllerMpd::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::servicePushState Mar 28 11:01:20 bar48 volumio[825]: info: CorePlayQueue::getTrack 0 Mar 28 11:01:20 bar48 volumio[825]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Capital T - Kujtime (Prod. BO Beatz) (1)","artist":"Alpo Radio","album":null,"uri":"http://95.217.32.113:8001/stream","trackType":""} Mar 28 11:01:20 bar48 volumio[825]: verbose: CURRENT POSITION 0 Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::syncState stateService play Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::syncState currentStatus play Mar 28 11:01:20 bar48 volumio[825]: info: Received an update from plugin. extracting info from payload Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreStateMachine::pushState Mar 28 11:01:20 bar48 volumio[825]: info: CoreCommandRouter::volumioPushState Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.526+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=325 volume=100 Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.528+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://95.217.32.113:8001/stream title="Capital T - Kujtime (Prod. BO Beatz) (1)" Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.529+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=325 volume=100 Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.529+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://95.217.32.113:8001/stream title="Capital T - Kujtime (Prod. BO Beatz) (1)" Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.531+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=325 volume=100 Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.532+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://95.217.32.113:8001/stream title="Capital T - Kujtime (Prod. BO Beatz) (1)" Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.534+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=325 volume=100 Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.536+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://95.217.32.113:8001/stream title="Capital T - Kujtime (Prod. BO Beatz) (1)" Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.538+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=325 volume=100 Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.539+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://95.217.32.113:8001/stream title="Capital T - Kujtime (Prod. BO Beatz) (1)" Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.539+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" state=STATUS_PLAYING positionMs=325 volume=100 Mar 28 11:01:20 bar48 volumio5-onboarding[1061]: time=2026-03-28T11:01:20.541+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:43088 @ 0x2c9aea0" id=http://95.217.32.113:8001/stream title="Capital T - Kujtime (Prod. BO Beatz) (1)" Mar 28 11:01:20 bar48 volumio[825]: info: ------------------------------ 185ms Mar 28 11:01:20 bar48 volumio[825]: info: ------------------------------ 181ms Mar 28 11:01:20 bar48 volumio[825]: info: ------------------------------ 180ms Mar 28 11:01:21 bar48 volumio[825]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 11:01:21 bar48 volumio[825]: TypeError: Cannot read property 'slice' of null Mar 28 11:01:21 bar48 volumio[825]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 28 11:01:21 bar48 volumio[825]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 28 11:01:21 bar48 volumio[825]: at Object.onceWrapper (events.js:422:26) Mar 28 11:01:21 bar48 volumio[825]: at ClientRequest.emit (events.js:315:20) Mar 28 11:01:21 bar48 volumio[825]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Mar 28 11:01:21 bar48 volumio[825]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Mar 28 11:01:21 bar48 volumio[825]: at TLSSocket.socketOnData (_http_client.js:509:22) Mar 28 11:01:21 bar48 volumio[825]: at TLSSocket.emit (events.js:315:20) Mar 28 11:01:21 bar48 volumio[825]: at addChunk (internal/streams/readable.js:309:12) Mar 28 11:01:21 bar48 volumio[825]: at readableAddChunk (internal/streams/readable.js:284:9) Mar 28 11:01:21 bar48 volumio[825]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Mar 28 11:01:21 bar48 volumio[825]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Mar 28 11:01:21 bar48 volumio[825]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 11:01:21 bar48 sudo[4905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-28 11:00 Mar 28 11:01:21 bar48 sudo[4905]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"