Oct 16 15:12:00 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 16 15:12:01 volumio volumio[1081]: info: Preload queue cleared Oct 16 15:12:05 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 16 15:12:05 volumio volumio[1081]: info: Preload queue cleared Oct 16 15:12:05 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 16 15:12:06 volumio volumio[1081]: info: Preload queue cleared Oct 16 15:12:10 volumio volumio[1081]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Crosby%20%26%20Nash/3cc5201d-ea14-4a8e-93dd-0ceea783120f.jpg' Oct 16 15:12:13 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 16 15:12:13 volumio volumio[1081]: info: Preload queue cleared Oct 16 15:12:18 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 16 15:12:18 volumio volumio[1081]: info: Preload queue cleared Oct 16 15:12:23 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 16 15:12:23 volumio volumio[1081]: info: Preload queue cleared Oct 16 15:12:23 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i17833 Oct 16 15:12:23 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i37630 Oct 16 15:12:23 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i56964 Oct 16 15:12:23 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i10933 Oct 16 15:12:23 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i52030 Oct 16 15:12:23 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i12618 Oct 16 15:12:23 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i43754 Oct 16 15:12:23 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i37632 Oct 16 15:12:23 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i34547 Oct 16 15:12:23 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i22487 Oct 16 15:12:23 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i34810 Oct 16 15:12:23 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i17833 in service upnp_browser Oct 16 15:12:23 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i37630 in service upnp_browser Oct 16 15:12:23 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i56964 in service upnp_browser Oct 16 15:12:23 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i10933 in service upnp_browser Oct 16 15:12:23 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i52030 in service upnp_browser Oct 16 15:12:23 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i12618 in service upnp_browser Oct 16 15:12:23 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i43754 in service upnp_browser Oct 16 15:12:23 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i37632 in service upnp_browser Oct 16 15:12:23 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i34547 in service upnp_browser Oct 16 15:12:23 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i22487 in service upnp_browser Oct 16 15:12:24 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72$*i34810 in service upnp_browser Oct 16 15:12:24 volumio volumio[1081]: info: Preload queue cleared Oct 16 15:12:24 volumio volumio[1081]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::ClearQueue Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::stop Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::updateTrackBlock Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::getTrackBlock Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:24 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:12:24 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::serviceStop Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:24 volumio volumio[1081]: info: CoreCommandRouter::serviceStop Oct 16 15:12:24 volumio volumio[1081]: info: [1792177944760] ControllerWebradio::stop Oct 16 15:12:24 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::clearPlayQueue Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::saveQueue Oct 16 15:12:24 volumio volumio[1081]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::addQueueItems Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::addQueueItems Oct 16 15:12:24 volumio volumio[1081]: info: Preload queue cleared Oct 16 15:12:24 volumio volumio[1081]: info: Adding Item to queue: upnp/folder/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72 Oct 16 15:12:24 volumio volumio[1081]: info: Exploding uri upnp/folder/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a72 in service upnp_browser Oct 16 15:12:24 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:24.768-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:12:24 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:24.768-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://stream.radioparadise.com/mellow-flacm title="Radio Paradise Mellow (FLAC+)" Oct 16 15:12:24 volumio volumio[1081]: info: Oct 16 15:12:24 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:12:24 volumio volumio[1081]: info: sendMpdCommand stop took 21 milliseconds Oct 16 15:12:24 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:12:24 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:12:24 volumio volumio[1081]: info: Oct 16 15:12:24 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:12:24 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:12:24 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:12:24 volumio volumio[1081]: info: Oct 16 15:12:24 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:12:24 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:12:24 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:12:24 volumio volumio[1081]: info: sendMpdCommand status took 11 milliseconds Oct 16 15:12:24 volumio volumio[1081]: info: sendMpdCommand status took 9 milliseconds Oct 16 15:12:24 volumio volumio[1081]: info: sendMpdCommand status took 7 milliseconds Oct 16 15:12:24 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:12:24 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:12:24 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:12:24 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:12:24 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:12:24 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:12:24 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 16 15:12:24 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 16 15:12:24 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 16 15:12:24 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:12:24 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:12:24 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:12:24 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:12:24 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:24 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:12:24 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:24 volumio volumio[1081]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Patty Griffin - Cold As It Gets","artist":"Radio Paradise: Mellow Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/mellow-flacm","trackType":""} Oct 16 15:12:24 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::syncState stateService stop Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:24 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:24 volumio volumio[1081]: info: No code Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:24 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:24 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:12:24 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:24 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:24 volumio volumio[1081]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Patty Griffin - Cold As It Gets","artist":"Radio Paradise: Mellow Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/mellow-flacm","trackType":""} Oct 16 15:12:24 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::syncState stateService stop Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:24 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:24 volumio volumio[1081]: info: No code Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:24 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:24 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:12:24 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:24 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:24 volumio volumio[1081]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Patty Griffin - Cold As It Gets","artist":"Radio Paradise: Mellow Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/mellow-flacm","trackType":""} Oct 16 15:12:24 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::syncState stateService stop Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:24 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:24 volumio volumio[1081]: info: No code Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:24 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:24 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:24.855-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:12:24 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:24.855-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id= title= Oct 16 15:12:24 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:24.858-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:12:24 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:24.858-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:12:24 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:24.858-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id= title= Oct 16 15:12:24 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:24.858-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id= title= Oct 16 15:12:24 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:24.858-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:12:24 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:24.858-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id= title= Oct 16 15:12:24 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:24.861-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:12:24 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:24.862-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:12:24 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:24.862-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:12:24 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:24.862-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:12:24 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:24.863-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id= title= Oct 16 15:12:24 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:24.862-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:12:24 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:24.863-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id= title= Oct 16 15:12:24 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:24.863-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id= title= Oct 16 15:12:24 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:24.863-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id= title= Oct 16 15:12:24 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:24.864-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id= title= Oct 16 15:12:24 volumio volumio[1081]: info: ------------------------------ 89ms Oct 16 15:12:24 volumio volumio[1081]: info: ------------------------------ 86ms Oct 16 15:12:24 volumio volumio[1081]: info: ------------------------------ 85ms Oct 16 15:12:24 volumio volumio[1081]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::saveQueue Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::updateTrackBlock Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::getTrackBlock Oct 16 15:12:24 volumio volumio[1081]: info: CoreCommandRouter::volumioPlay Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::play index 0 Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::stop Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::play index undefined Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:24 volumio volumio[1081]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:12:24 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:24 volumio volumio[1081]: info: [1792177944927] ControllerUPNPBrowser::clearAddPlayTrack Oct 16 15:12:24 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:12:24 volumio volumio[1081]: info: sendMpdCommand stop took 30 milliseconds Oct 16 15:12:24 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 15:12:24 volumio volumio[1081]: info: Oct 16 15:12:24 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:12:24 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:12:24 volumio volumio[1081]: info: sendMpdCommand clear took 8 milliseconds Oct 16 15:12:24 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3" Oct 16 15:12:24 volumio volumio[1081]: info: Oct 16 15:12:24 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:12:24 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:12:24 volumio volumio[1081]: info: Oct 16 15:12:24 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:12:24 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:12:24 volumio volumio[1081]: error: updateQueue error: null Oct 16 15:12:24 volumio volumio[1081]: info: ------------------------------ 13ms Oct 16 15:12:24 volumio volumio[1081]: error: updateQueue error: null Oct 16 15:12:24 volumio volumio[1081]: error: updateQueue error: null Oct 16 15:12:24 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3" Oct 16 15:12:24 volumio volumio[1081]: info: ------------------------------ 25ms Oct 16 15:12:24 volumio volumio[1081]: info: ------------------------------ 24ms Oct 16 15:12:25 volumio volumio[1081]: info: Oct 16 15:12:25 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:12:25 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:12:25 volumio volumio[1081]: info: sendMpdCommand add "http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3" took 12 milliseconds Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand play Oct 16 15:12:25 volumio volumio[1081]: info: Oct 16 15:12:25 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:12:25 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:12:25 volumio volumio[1081]: info: Oct 16 15:12:25 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:12:25 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:12:25 volumio volumio[1081]: info: Oct 16 15:12:25 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:12:25 volumio volumio[1081]: info: ------------------------------ 88ms Oct 16 15:12:25 volumio volumio[1081]: info: sendMpdCommand play took 86 milliseconds Oct 16 15:12:25 volumio volumio[1081]: info: ------------------------------ 85ms Oct 16 15:12:25 volumio volumio[1081]: info: ------------------------------ 83ms Oct 16 15:12:25 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:12:25 volumio volumio[1081]: info: Oct 16 15:12:25 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:12:25 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:12:25 volumio volumio[1081]: info: Oct 16 15:12:25 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:12:25 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:12:25 volumio volumio[1081]: info: Oct 16 15:12:25 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:12:25 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:12:25 volumio volumio[1081]: info: Oct 16 15:12:25 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:12:25 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:12:25 volumio volumio[1081]: info: sendMpdCommand status took 8 milliseconds Oct 16 15:12:25 volumio volumio[1081]: info: sendMpdCommand status took 6 milliseconds Oct 16 15:12:25 volumio volumio[1081]: info: sendMpdCommand status took 5 milliseconds Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:12:25 volumio volumio[1081]: info: Oct 16 15:12:25 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:12:25 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:12:25 volumio volumio[1081]: info: Oct 16 15:12:25 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:12:25 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:12:25 volumio volumio[1081]: info: Oct 16 15:12:25 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:12:25 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:12:25 volumio volumio[1081]: info: Oct 16 15:12:25 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:12:25 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:12:25 volumio volumio[1081]: info: ------------------------------ 12ms Oct 16 15:12:25 volumio volumio[1081]: info: sendMpdCommand status took 10 milliseconds Oct 16 15:12:25 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 16 15:12:25 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 16 15:12:25 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 16 15:12:25 volumio volumio[1081]: info: ------------------------------ 8ms Oct 16 15:12:25 volumio volumio[1081]: info: sendMpdCommand status took 7 milliseconds Oct 16 15:12:25 volumio volumio[1081]: info: ------------------------------ 7ms Oct 16 15:12:25 volumio volumio[1081]: info: sendMpdCommand status took 6 milliseconds Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:12:25 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:12:25 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:25 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":190,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"First Love","artist":"Adele","album":"19","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3","trackType":"mp3"} Oct 16 15:12:25 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:12:25 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:12:25 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:25 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":190,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"First Love","artist":"Adele","album":"19","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3","trackType":"mp3"} Oct 16 15:12:25 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:12:25 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:25 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:12:25 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:25 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":190,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"First Love","artist":"Adele","album":"19","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3","trackType":"mp3"} Oct 16 15:12:25 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:12:25 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:25 volumio volumio[1081]: info: ------------------------------ 46ms Oct 16 15:12:25 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:25.147-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=881 volume=45 Oct 16 15:12:25 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:25.147-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3 title="First Love" Oct 16 15:12:25 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:25.149-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=881 volume=45 Oct 16 15:12:25 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:25.150-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3 title="First Love" Oct 16 15:12:25 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:25.151-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=881 volume=45 Oct 16 15:12:25 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:25.156-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3 title="First Love" Oct 16 15:12:25 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:25.161-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=881 volume=45 Oct 16 15:12:25 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:25.161-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3 title="First Love" Oct 16 15:12:25 volumio volumio[1081]: info: ------------------------------ 67ms Oct 16 15:12:25 volumio volumio[1081]: info: ------------------------------ 69ms Oct 16 15:12:25 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 64 milliseconds Oct 16 15:12:25 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 63 milliseconds Oct 16 15:12:25 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 63 milliseconds Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:12:25 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:12:25 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:12:25 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:25 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":190,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"First Love","artist":"Adele","album":"19","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3","trackType":"mp3"} Oct 16 15:12:25 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:12:25 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:25 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:12:25 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:25 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":190,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"First Love","artist":"Adele","album":"19","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3","trackType":"mp3"} Oct 16 15:12:25 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:12:25 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:25 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:12:25 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:25 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":190,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"First Love","artist":"Adele","album":"19","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3","trackType":"mp3"} Oct 16 15:12:25 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:12:25 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:25 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:25.239-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=1132 volume=45 Oct 16 15:12:25 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:25.240-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=1132 volume=45 Oct 16 15:12:25 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:25.241-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3 title="First Love" Oct 16 15:12:25 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:25.240-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3 title="First Love" Oct 16 15:12:25 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:25.242-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=1132 volume=45 Oct 16 15:12:25 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:25.243-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3 title="First Love" Oct 16 15:12:25 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:25.247-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=1132 volume=45 Oct 16 15:12:25 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:25.248-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3 title="First Love" Oct 16 15:12:25 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:25.247-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=1132 volume=45 Oct 16 15:12:25 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:25.248-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3 title="First Love" Oct 16 15:12:25 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:25.251-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=1132 volume=45 Oct 16 15:12:25 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:25.252-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3 title="First Love" Oct 16 15:12:25 volumio volumio[1081]: info: ------------------------------ 166ms Oct 16 15:12:25 volumio volumio[1081]: info: ------------------------------ 162ms Oct 16 15:12:25 volumio volumio[1081]: info: ------------------------------ 161ms Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:12:25 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:12:25 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:12:25 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:12:25 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:12:25 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:12:25 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:12:25 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:12:25 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:12:25 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:12:25 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:12:25 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:12:25 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:12:31 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 16 15:12:32 volumio volumio[1081]: info: Preload queue cleared Oct 16 15:12:32 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i35395 Oct 16 15:12:32 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i13452 Oct 16 15:12:32 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i7504 Oct 16 15:12:32 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i10932 Oct 16 15:12:32 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i11673 Oct 16 15:12:32 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i12617 Oct 16 15:12:32 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i35393 Oct 16 15:12:32 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i17834 Oct 16 15:12:32 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i43753 Oct 16 15:12:32 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i34545 Oct 16 15:12:32 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i37631 Oct 16 15:12:32 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i56965 Oct 16 15:12:32 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i22486 Oct 16 15:12:32 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i52159 Oct 16 15:12:32 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i34809 Oct 16 15:12:32 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i35395 in service upnp_browser Oct 16 15:12:32 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i13452 in service upnp_browser Oct 16 15:12:32 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i7504 in service upnp_browser Oct 16 15:12:32 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i10932 in service upnp_browser Oct 16 15:12:32 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i11673 in service upnp_browser Oct 16 15:12:32 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i12617 in service upnp_browser Oct 16 15:12:32 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i35393 in service upnp_browser Oct 16 15:12:32 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i17834 in service upnp_browser Oct 16 15:12:32 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i43753 in service upnp_browser Oct 16 15:12:32 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i34545 in service upnp_browser Oct 16 15:12:32 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i37631 in service upnp_browser Oct 16 15:12:32 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i56965 in service upnp_browser Oct 16 15:12:32 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i22486 in service upnp_browser Oct 16 15:12:32 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i52159 in service upnp_browser Oct 16 15:12:32 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a74$*i34809 in service upnp_browser Oct 16 15:12:39 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 16 15:12:39 volumio volumio[1081]: info: Preload queue cleared Oct 16 15:12:42 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 16 15:12:42 volumio volumio[1081]: info: Preload queue cleared Oct 16 15:12:47 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 16 15:12:47 volumio volumio[1081]: info: Preload queue cleared Oct 16 15:12:47 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a73$*i35394 Oct 16 15:12:47 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a73$*i35394 in service upnp_browser Oct 16 15:12:52 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 16 15:12:52 volumio volumio[1081]: info: Preload queue cleared Oct 16 15:12:52 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i21588 Oct 16 15:12:52 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i45559 Oct 16 15:12:52 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i24787 Oct 16 15:12:52 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i60995 Oct 16 15:12:52 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i43461 Oct 16 15:12:52 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i59647 Oct 16 15:12:52 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i43862 Oct 16 15:12:52 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i33467 Oct 16 15:12:52 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i35755 Oct 16 15:12:52 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i4481 Oct 16 15:12:52 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i50734 Oct 16 15:12:52 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i21588 in service upnp_browser Oct 16 15:12:52 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i45559 in service upnp_browser Oct 16 15:12:52 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i24787 in service upnp_browser Oct 16 15:12:52 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i60995 in service upnp_browser Oct 16 15:12:52 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i43461 in service upnp_browser Oct 16 15:12:52 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i59647 in service upnp_browser Oct 16 15:12:53 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i43862 in service upnp_browser Oct 16 15:12:53 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i33467 in service upnp_browser Oct 16 15:12:53 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i35755 in service upnp_browser Oct 16 15:12:53 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i4481 in service upnp_browser Oct 16 15:12:53 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92$*i50734 in service upnp_browser Oct 16 15:12:54 volumio volumio[1081]: info: Preload queue cleared Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::ClearQueue Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::stop Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::updateTrackBlock Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::getTrackBlock Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::serviceStop Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::serviceStop Oct 16 15:12:54 volumio volumio[1081]: info: [1792177974660] ControllerUPNPBrowser::stop Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::clearPlayQueue Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::saveQueue Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::addQueueItems Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::addQueueItems Oct 16 15:12:54 volumio volumio[1081]: info: Preload queue cleared Oct 16 15:12:54 volumio volumio[1081]: info: Adding Item to queue: upnp/folder/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92 Oct 16 15:12:54 volumio volumio[1081]: info: Exploding uri upnp/folder/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a92 in service upnp_browser Oct 16 15:12:54 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:54.667-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:12:54 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:54.667-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3 title="First Love" Oct 16 15:12:54 volumio volumio[1081]: info: Oct 16 15:12:54 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:12:54 volumio volumio[1081]: info: sendMpdCommand stop took 17 milliseconds Oct 16 15:12:54 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:12:54 volumio volumio[1081]: info: sendMpdCommand status took 1 milliseconds Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:12:54 volumio volumio[1081]: info: Oct 16 15:12:54 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:12:54 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:12:54 volumio volumio[1081]: info: Oct 16 15:12:54 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:12:54 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:12:54 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 16 15:12:54 volumio volumio[1081]: info: sendMpdCommand status took 9 milliseconds Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:12:54 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:54 volumio volumio[1081]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"First Love","artist":"Adele","album":"19","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3","trackType":"mp3"} Oct 16 15:12:54 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::syncState stateService stop Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:54 volumio volumio[1081]: info: No code Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:54 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:54.713-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:12:54 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:54.714-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id= title= Oct 16 15:12:54 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:54.715-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:12:54 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:54.717-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id= title= Oct 16 15:12:54 volumio volumio[1081]: info: ------------------------------ 40ms Oct 16 15:12:54 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:54.719-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:12:54 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:54.721-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id= title= Oct 16 15:12:54 volumio volumio[1081]: info: sendMpdCommand status took 38 milliseconds Oct 16 15:12:54 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 30 milliseconds Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:12:54 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:54 volumio volumio[1081]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"First Love","artist":"Adele","album":"19","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3","trackType":"mp3"} Oct 16 15:12:54 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::syncState stateService stop Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:54 volumio volumio[1081]: info: No code Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:54 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:54.740-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:12:54 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:54.740-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id= title= Oct 16 15:12:54 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:54.741-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:12:54 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:54.741-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id= title= Oct 16 15:12:54 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:54.742-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:12:54 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:54.743-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id= title= Oct 16 15:12:54 volumio volumio[1081]: info: ------------------------------ 62ms Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::saveQueue Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::updateTrackBlock Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::getTrackBlock Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::volumioPlay Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::play index 0 Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::stop Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::play index undefined Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:54 volumio volumio[1081]: info: [1792177974776] ControllerUPNPBrowser::clearAddPlayTrack Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:12:54 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 55 milliseconds Oct 16 15:12:54 volumio volumio[1081]: info: sendMpdCommand stop took 3 milliseconds Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 15:12:54 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:54 volumio volumio[1081]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"First Love","artist":"Adele","album":"19","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/07*20First*20Love.mp3","trackType":"mp3"} Oct 16 15:12:54 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::syncState stateService stop Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:54 volumio volumio[1081]: info: No code Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:54 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:54 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:54 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:54.791-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:12:54 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:54.792-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3 title=Hello Oct 16 15:12:54 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:54.792-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:12:54 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:54.792-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3 title=Hello Oct 16 15:12:54 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:54.793-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:12:54 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:54.794-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3 title=Hello Oct 16 15:12:54 volumio volumio[1081]: info: ------------------------------ 111ms Oct 16 15:12:54 volumio volumio[1081]: info: Oct 16 15:12:54 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:12:54 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:12:54 volumio volumio[1081]: info: sendMpdCommand clear took 28 milliseconds Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3" Oct 16 15:12:54 volumio volumio[1081]: info: Oct 16 15:12:54 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:12:54 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:12:54 volumio volumio[1081]: info: Oct 16 15:12:54 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:12:54 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:12:54 volumio volumio[1081]: error: updateQueue error: null Oct 16 15:12:54 volumio volumio[1081]: info: ------------------------------ 8ms Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3" Oct 16 15:12:54 volumio volumio[1081]: error: updateQueue error: null Oct 16 15:12:54 volumio volumio[1081]: error: updateQueue error: null Oct 16 15:12:54 volumio volumio[1081]: info: Oct 16 15:12:54 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:12:54 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:12:54 volumio volumio[1081]: info: ------------------------------ 127ms Oct 16 15:12:54 volumio volumio[1081]: info: ------------------------------ 125ms Oct 16 15:12:54 volumio volumio[1081]: info: sendMpdCommand add "http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3" took 3 milliseconds Oct 16 15:12:54 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand play Oct 16 15:12:54 volumio volumio[1081]: info: ------------------------------ 2ms Oct 16 15:12:54 volumio volumio[1081]: info: Oct 16 15:12:54 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:12:54 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:12:54 volumio volumio[1081]: info: Oct 16 15:12:54 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:12:54 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:12:54 volumio volumio[1081]: info: sendMpdCommand play took 4 milliseconds Oct 16 15:12:54 volumio volumio[1081]: info: ------------------------------ 2ms Oct 16 15:12:54 volumio volumio[1081]: info: ------------------------------ 2ms Oct 16 15:12:54 volumio volumio[1081]: info: Oct 16 15:12:54 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:12:54 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:12:54 volumio volumio[1081]: info: Oct 16 15:12:54 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:12:54 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:12:54 volumio volumio[1081]: info: Oct 16 15:12:54 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:12:54 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:12:54 volumio volumio[1081]: info: Oct 16 15:12:54 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:12:54 volumio volumio[1081]: info: sendMpdCommand status took 32 milliseconds Oct 16 15:12:54 volumio volumio[1081]: info: sendMpdCommand status took 31 milliseconds Oct 16 15:12:54 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:12:54 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:12:54 volumio volumio[1081]: info: Oct 16 15:12:54 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:12:55 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:12:55 volumio volumio[1081]: info: sendMpdCommand status took 37 milliseconds Oct 16 15:12:55 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:12:55 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:12:55 volumio volumio[1081]: info: Oct 16 15:12:55 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:12:55 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:12:55 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:12:55 volumio volumio[1081]: info: Oct 16 15:12:55 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:12:55 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:12:55 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:12:55 volumio volumio[1081]: info: sendMpdCommand status took 19 milliseconds Oct 16 15:12:55 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 16 15:12:55 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 16 15:12:55 volumio volumio[1081]: info: ------------------------------ 18ms Oct 16 15:12:55 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 16 15:12:55 volumio volumio[1081]: info: sendMpdCommand status took 9 milliseconds Oct 16 15:12:55 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:12:55 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:12:55 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:12:55 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:12:55 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:12:55 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:12:55 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:12:55 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:12:55 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:55 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":296,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hello","artist":"Adele","album":"25","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3","trackType":"mp3"} Oct 16 15:12:55 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:12:55 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:12:55 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:55 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":296,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Hello","artist":"Adele","album":"25","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3","trackType":"mp3"} Oct 16 15:12:55 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:12:55 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:55 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:12:55 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:55 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":778,"duration":296,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Hello","artist":"Adele","album":"25","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3","trackType":"mp3"} Oct 16 15:12:55 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:12:55 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:55 volumio volumio[1081]: info: ------------------------------ 75ms Oct 16 15:12:55 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:55.041-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=0 volume=45 Oct 16 15:12:55 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:55.041-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3 title=Hello Oct 16 15:12:55 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:55.043-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=0 volume=45 Oct 16 15:12:55 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:55.044-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3 title=Hello Oct 16 15:12:55 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:55.045-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=0 volume=45 Oct 16 15:12:55 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:55.045-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3 title=Hello Oct 16 15:12:55 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:55.046-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=0 volume=45 Oct 16 15:12:55 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:55.049-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3 title=Hello Oct 16 15:12:55 volumio volumio[1081]: info: ------------------------------ 94ms Oct 16 15:12:55 volumio volumio[1081]: info: ------------------------------ 96ms Oct 16 15:12:55 volumio volumio[1081]: info: Oct 16 15:12:55 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:12:55 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:12:55 volumio volumio[1081]: info: Oct 16 15:12:55 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:12:55 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:12:55 volumio volumio[1081]: info: sendMpdCommand status took 53 milliseconds Oct 16 15:12:55 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 47 milliseconds Oct 16 15:12:55 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 47 milliseconds Oct 16 15:12:55 volumio volumio[1081]: info: ------------------------------ 6ms Oct 16 15:12:55 volumio volumio[1081]: info: ------------------------------ 5ms Oct 16 15:12:55 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:12:55 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:12:55 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:12:55 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:12:55 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:12:55 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:55 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":296,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Hello","artist":"Adele","album":"25","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3","trackType":"mp3"} Oct 16 15:12:55 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:12:55 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:55 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:12:55 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:55 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":296,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Hello","artist":"Adele","album":"25","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3","trackType":"mp3"} Oct 16 15:12:55 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:12:55 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:55 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:55.097-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=255 volume=45 Oct 16 15:12:55 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:55.097-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3 title=Hello Oct 16 15:12:55 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:55.100-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=255 volume=45 Oct 16 15:12:55 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:55.100-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=255 volume=45 Oct 16 15:12:55 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:55.100-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3 title=Hello Oct 16 15:12:55 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:55.100-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3 title=Hello Oct 16 15:12:55 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:55.102-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=255 volume=45 Oct 16 15:12:55 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:55.105-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3 title=Hello Oct 16 15:12:55 volumio volumio[1081]: info: ------------------------------ 119ms Oct 16 15:12:55 volumio volumio[1081]: info: ------------------------------ 104ms Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:12:55 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:12:55 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:12:55 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:12:55 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:12:55 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:12:55 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:12:55 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:12:55 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:12:55 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 138 milliseconds Oct 16 15:12:55 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:12:55 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:12:55 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:12:55 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":296,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Hello","artist":"Adele","album":"25","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3","trackType":"mp3"} Oct 16 15:12:55 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:12:55 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:12:55 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:55.218-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=255 volume=45 Oct 16 15:12:55 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:55.219-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3 title=Hello Oct 16 15:12:55 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:55.219-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=255 volume=45 Oct 16 15:12:55 volumio volumio5-onboarding[1591]: time=2026-10-16T15:12:55.220-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3 title=Hello Oct 16 15:12:55 volumio volumio[1081]: info: ------------------------------ 216ms Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:12:55 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:12:55 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:12:55 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:12:55 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:13:01 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 16 15:13:01 volumio volumio[1081]: info: Preload queue cleared Oct 16 15:13:01 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i49758 Oct 16 15:13:01 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i15981 Oct 16 15:13:01 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i37421 Oct 16 15:13:01 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i12853 Oct 16 15:13:01 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i39451 Oct 16 15:13:01 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i10129 Oct 16 15:13:01 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i24004 Oct 16 15:13:01 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i4593 Oct 16 15:13:01 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i62250 Oct 16 15:13:01 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i22311 Oct 16 15:13:01 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i57001 Oct 16 15:13:01 volumio volumio[1081]: info: Preloading song: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i33477 Oct 16 15:13:01 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i49758 in service upnp_browser Oct 16 15:13:01 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i15981 in service upnp_browser Oct 16 15:13:01 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i37421 in service upnp_browser Oct 16 15:13:01 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i12853 in service upnp_browser Oct 16 15:13:01 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i39451 in service upnp_browser Oct 16 15:13:01 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i10129 in service upnp_browser Oct 16 15:13:01 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i24004 in service upnp_browser Oct 16 15:13:02 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i4593 in service upnp_browser Oct 16 15:13:02 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i62250 in service upnp_browser Oct 16 15:13:02 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i22311 in service upnp_browser Oct 16 15:13:02 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i57001 in service upnp_browser Oct 16 15:13:02 volumio volumio[1081]: info: Exploding uri upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i33477 in service upnp_browser Oct 16 15:13:03 volumio volumio[1081]: info: Preload queue cleared Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::ClearQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::stop Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::stPlaybackTimer Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::updateTrackBlock Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrackBlock Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::serviceStop Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::serviceStop Oct 16 15:13:03 volumio volumio[1081]: info: [1792177983232] ControllerUPNPBrowser::stop Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::clearPlayQueue Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::saveQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::addQueueItems Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::addQueueItems Oct 16 15:13:03 volumio volumio[1081]: info: Preload queue cleared Oct 16 15:13:03 volumio volumio[1081]: info: Adding Item to queue: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i49758 Oct 16 15:13:03 volumio volumio[1081]: info: Using cached record of: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i49758 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.236-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.236-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/25/01*20Hello.mp3 title=Hello Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::saveQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::updateTrackBlock Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrackBlock Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPlay Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::play index 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::addQueueItems Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::addQueueItems Oct 16 15:13:03 volumio volumio[1081]: info: Preload queue cleared Oct 16 15:13:03 volumio volumio[1081]: info: Adding Item to queue: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i15981 Oct 16 15:13:03 volumio volumio[1081]: info: Using cached record of: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i15981 Oct 16 15:13:03 volumio volumio[1081]: info: Adding Item to queue: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i37421 Oct 16 15:13:03 volumio volumio[1081]: info: Using cached record of: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i37421 Oct 16 15:13:03 volumio volumio[1081]: info: Adding Item to queue: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i12853 Oct 16 15:13:03 volumio volumio[1081]: info: Using cached record of: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i12853 Oct 16 15:13:03 volumio volumio[1081]: info: Adding Item to queue: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i39451 Oct 16 15:13:03 volumio volumio[1081]: info: Using cached record of: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i39451 Oct 16 15:13:03 volumio volumio[1081]: info: Adding Item to queue: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i10129 Oct 16 15:13:03 volumio volumio[1081]: info: Using cached record of: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i10129 Oct 16 15:13:03 volumio volumio[1081]: info: Adding Item to queue: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i24004 Oct 16 15:13:03 volumio volumio[1081]: info: Using cached record of: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i24004 Oct 16 15:13:03 volumio volumio[1081]: info: Adding Item to queue: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i4593 Oct 16 15:13:03 volumio volumio[1081]: info: Using cached record of: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i4593 Oct 16 15:13:03 volumio volumio[1081]: info: Adding Item to queue: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i62250 Oct 16 15:13:03 volumio volumio[1081]: info: Using cached record of: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i62250 Oct 16 15:13:03 volumio volumio[1081]: info: Adding Item to queue: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i22311 Oct 16 15:13:03 volumio volumio[1081]: info: Using cached record of: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i22311 Oct 16 15:13:03 volumio volumio[1081]: info: Adding Item to queue: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i57001 Oct 16 15:13:03 volumio volumio[1081]: info: Using cached record of: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i57001 Oct 16 15:13:03 volumio volumio[1081]: info: Adding Item to queue: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i33477 Oct 16 15:13:03 volumio volumio[1081]: info: Using cached record of: upnp/http://192.168.2.35:9791/0b9f6bcf-7cc7-460e-b4a9-e09b71611978/upnp.org-ContentDirectory-1/control@0$=Artist$17157$albums$*a99$*i33477 Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::stop Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushQueue Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::saveQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::play index undefined Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::updateTrackBlock Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrackBlock Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: info: [1792177983263] ControllerUPNPBrowser::clearAddPlayTrack Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand stop took 39 milliseconds Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand stop took 9 milliseconds Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:13:03 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand status took 6 milliseconds Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand clear took 5 milliseconds Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand status took 5 milliseconds Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand status took 3 milliseconds Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3" Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: 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} Oct 16 15:13:03 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState stateService stop Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio[1081]: info: No code Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: 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} Oct 16 15:13:03 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState stateService stop Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio[1081]: info: No code Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.300-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.301-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.303-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.304-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.304-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.305-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.305-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.306-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.306-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.307-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.308-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_STOPPED positionMs=0 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.308-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 39ms Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 39ms Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:13:03 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:13:03 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:13:03 volumio volumio[1081]: error: updateQueue error: null Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 44ms Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 42 milliseconds Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:13:03 volumio volumio[1081]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 53ms Oct 16 15:13:03 volumio volumio[1081]: error: updateQueue error: null Oct 16 15:13:03 volumio volumio[1081]: error: updateQueue error: null Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3" Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 54ms Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 53ms Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:13:03 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand add "http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3" took 3 milliseconds Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand play Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:13:03 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:13:03 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 7ms Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand play took 6 milliseconds Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 6ms Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 5ms Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand status took 40 milliseconds Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand status took 39 milliseconds Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand status took 38 milliseconds Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:13:03 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand status took 22 milliseconds Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand status took 10 milliseconds Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Strangers By Nature","artist":"Adele","album":"30","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3","trackType":"mp3"} Oct 16 15:13:03 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus stop Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Strangers By Nature","artist":"Adele","album":"30","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3","trackType":"mp3"} Oct 16 15:13:03 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:13:03 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Strangers By Nature","artist":"Adele","album":"30","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3","trackType":"mp3"} Oct 16 15:13:03 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:13:03 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 89ms Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.488-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=0 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.489-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.489-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=0 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.490-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.491-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=0 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.491-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 101ms Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.495-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=0 volume=45 Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 101ms Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.498-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:13:03 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 16 15:13:03 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 16 15:13:03 volumio volumio[1081]: info: Oct 16 15:13:03 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::getState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand status took 57 milliseconds Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 57ms Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand status took 54 milliseconds Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 50 milliseconds Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 46 milliseconds Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 13ms Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand status took 10 milliseconds Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 9ms Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand status took 7 milliseconds Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":220,"duration":182,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Strangers By Nature","artist":"Adele","album":"30","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3","trackType":"mp3"} Oct 16 15:13:03 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:13:03 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":182,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Strangers By Nature","artist":"Adele","album":"30","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3","trackType":"mp3"} Oct 16 15:13:03 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:13:03 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.537-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=0 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.537-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=0 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.538-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.539-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=0 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.540-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.541-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=0 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.542-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.542-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 112ms Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 105ms Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 132 milliseconds Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 133 milliseconds Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 133 milliseconds Oct 16 15:13:03 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 134 milliseconds Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:13:03 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":182,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Strangers By Nature","artist":"Adele","album":"30","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3","trackType":"mp3"} Oct 16 15:13:03 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:13:03 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":182,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Strangers By Nature","artist":"Adele","album":"30","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3","trackType":"mp3"} Oct 16 15:13:03 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:13:03 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":182,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Strangers By Nature","artist":"Adele","album":"30","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3","trackType":"mp3"} Oct 16 15:13:03 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:13:03 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio[1081]: info: ControllerMpd::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 16 15:13:03 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":182,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Strangers By Nature","artist":"Adele","album":"30","uri":"http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3","trackType":"mp3"} Oct 16 15:13:03 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:13:03 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.681-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=190 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.682-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.683-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=190 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.684-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=190 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.684-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.685-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.685-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=190 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.686-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=190 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.686-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.687-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=190 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.687-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.686-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.690-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=190 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.691-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.693-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" state=STATUS_PLAYING positionMs=190 volume=45 Oct 16 15:13:03 volumio volumio5-onboarding[1591]: time=2026-10-16T15:13:03.694-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.195:59142 @ 0x3000cf0" id=http://192.168.2.35:9790/minimserver/*/music*20library*20backup/Rock/Adele/30/01*20Adele*20-*20Strangers*20By*20Nature.mp3 title="Strangers By Nature" Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 253ms Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 251ms Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 207ms Oct 16 15:13:03 volumio volumio[1081]: info: ------------------------------ 204ms Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 16 15:13:03 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 16 15:13:03 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 16 15:13:30 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 16 15:13:30 volumio volumio[1081]: info: Preload queue cleared Oct 16 15:13:32 volumio volumio[1081]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 15:13:32 volumio volumio[1081]: TypeError: Cannot read properties of null (reading 'slice') Oct 16 15:13:32 volumio volumio[1081]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Oct 16 15:13:32 volumio volumio[1081]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Oct 16 15:13:32 volumio volumio[1081]: at Object.onceWrapper (node:events:629:26) Oct 16 15:13:32 volumio volumio[1081]: at ClientRequest.emit (node:events:514:28) Oct 16 15:13:32 volumio volumio[1081]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Oct 16 15:13:32 volumio volumio[1081]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Oct 16 15:13:32 volumio volumio[1081]: at TLSSocket.socketOnData (node:_http_client:535:22) Oct 16 15:13:32 volumio volumio[1081]: at TLSSocket.emit (node:events:514:28) Oct 16 15:13:32 volumio volumio[1081]: at addChunk (node:internal/streams/readable:343:12) Oct 16 15:13:32 volumio volumio[1081]: at readableAddChunk (node:internal/streams/readable:316:9) Oct 16 15:13:32 volumio volumio[1081]: at Readable.push (node:internal/streams/readable:253:10) Oct 16 15:13:32 volumio volumio[1081]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Oct 16 15:13:32 volumio volumio[1081]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 15:13:33 volumio sudo[854]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-16 15:12' Oct 16 15:13:33 volumio sudo[854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"