Feb 24 19:25:12 siren volumio[3223]: info: CorePlayQueue::getTrack 11 Feb 24 19:25:12 siren volumio[3223]: info: CorePlayQueue::getTrack 12 Feb 24 19:25:12 siren volumio[3223]: info: Prefetching next song Feb 24 19:25:12 siren volumio[3223]: info: DOING PREFETCH IN MPD Feb 24 19:25:12 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand add "USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/18 - Five Short Minutes.flac" Feb 24 19:25:12 siren volumio[3223]: info: Feb 24 19:25:12 siren volumio[3223]: ---------------------------- MPD announces system playlist update Feb 24 19:25:12 siren volumio[3223]: info: Ignoring MPD Status Update Feb 24 19:25:12 siren volumio[3223]: info: sendMpdCommand add "USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/18 - Five Short Minutes.flac" took 1 milliseconds Feb 24 19:25:12 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 24 19:25:12 siren volumio[3223]: info: Feb 24 19:25:12 siren volumio[3223]: ---------------------------- MPD announces system playlist update Feb 24 19:25:12 siren volumio[3223]: info: Ignoring MPD Status Update Feb 24 19:25:12 siren volumio[3223]: info: ------------------------------ 1ms Feb 24 19:25:12 siren volumio[3223]: info: sendMpdCommand consume 1 took 1 milliseconds Feb 24 19:25:12 siren volumio[3223]: info: ------------------------------ 1ms Feb 24 19:25:12 siren volumio[3223]: info: Feb 24 19:25:12 siren volumio[3223]: ---------------------------- MPD announces system playlist update Feb 24 19:25:12 siren volumio[3223]: info: Ignoring MPD Status Update Feb 24 19:25:12 siren volumio[3223]: info: ------------------------------ 1ms Feb 24 19:25:17 siren volumio[3223]: info: CoreStateMachine::startPlaybackTimer Feb 24 19:25:17 siren volumio[3223]: info: CorePlayQueue::getTrack 12 Feb 24 19:25:17 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:17 siren volumio[3223]: info: CorePlayQueue::getTrack 12 Feb 24 19:25:17 siren volumio[3223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 19:25:17 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:17 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:17.653-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_PLAYING positionMs=250 volume= Feb 24 19:25:17 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:17.653-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/18 - Five Short Minutes.flac" title="18 - Five Short Minutes.flac" Feb 24 19:25:17 siren volumio[3223]: info: Feb 24 19:25:17 siren volumio[3223]: ---------------------------- MPD announces system playlist update Feb 24 19:25:17 siren volumio[3223]: info: Ignoring MPD Status Update Feb 24 19:25:17 siren volumio[3223]: info: Feb 24 19:25:17 siren volumio[3223]: ---------------------------- MPD announces state update: player Feb 24 19:25:17 siren volumio[3223]: info: ControllerMpd::getState Feb 24 19:25:17 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:25:17 siren volumio[3223]: info: Feb 24 19:25:17 siren volumio[3223]: ---------------------------- MPD announces system playlist update Feb 24 19:25:17 siren volumio[3223]: info: Ignoring MPD Status Update Feb 24 19:25:17 siren volumio[3223]: info: Feb 24 19:25:17 siren volumio[3223]: ---------------------------- MPD announces state update: player Feb 24 19:25:17 siren volumio[3223]: info: ControllerMpd::getState Feb 24 19:25:17 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:25:17 siren volumio[3223]: info: Feb 24 19:25:17 siren volumio[3223]: ---------------------------- MPD announces system playlist update Feb 24 19:25:17 siren volumio[3223]: info: Ignoring MPD Status Update Feb 24 19:25:17 siren volumio[3223]: info: Feb 24 19:25:17 siren volumio[3223]: ---------------------------- MPD announces state update: player Feb 24 19:25:17 siren volumio[3223]: info: ControllerMpd::getState Feb 24 19:25:17 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:25:17 siren volumio[3223]: info: ------------------------------ 2ms Feb 24 19:25:17 siren volumio[3223]: info: sendMpdCommand status took 1 milliseconds Feb 24 19:25:17 siren volumio[3223]: info: ------------------------------ 1ms Feb 24 19:25:17 siren volumio[3223]: info: sendMpdCommand status took 1 milliseconds Feb 24 19:25:17 siren volumio[3223]: verbose: ControllerMpd::parseState Feb 24 19:25:17 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:25:17 siren volumio[3223]: verbose: ControllerMpd::parseState Feb 24 19:25:17 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:25:17 siren volumio[3223]: info: ------------------------------ 2ms Feb 24 19:25:17 siren volumio[3223]: info: sendMpdCommand status took 1 milliseconds Feb 24 19:25:17 siren volumio[3223]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 19:25:17 siren volumio[3223]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 19:25:17 siren volumio[3223]: verbose: ControllerMpd::parseState Feb 24 19:25:17 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:25:17 siren volumio[3223]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:25:17 siren volumio[3223]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:25:17 siren volumio[3223]: info: ControllerMpd::pushState Feb 24 19:25:17 siren volumio[3223]: info: CoreCommandRouter::servicePushState Feb 24 19:25:17 siren volumio[3223]: info: CorePlayQueue::getTrack 12 Feb 24 19:25:17 siren volumio[3223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":81,"duration":211,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3445 Kbps","isStreaming":false,"title":"18 - Five Short Minutes.flac","artist":null,"album":"IPRD","uri":"USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/18 - Five Short Minutes.flac","trackType":"flac"} Feb 24 19:25:17 siren volumio[3223]: verbose: CURRENT POSITION 12 Feb 24 19:25:17 siren volumio[3223]: info: CoreStateMachine::syncState stateService play Feb 24 19:25:17 siren volumio[3223]: info: CoreStateMachine::syncState currentStatus play Feb 24 19:25:17 siren volumio[3223]: info: Received an update from plugin. extracting info from payload Feb 24 19:25:17 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:17 siren volumio[3223]: info: CorePlayQueue::getTrack 12 Feb 24 19:25:17 siren volumio[3223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 19:25:17 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:17 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:17 siren volumio[3223]: info: CorePlayQueue::getTrack 12 Feb 24 19:25:17 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:17 siren volumio[3223]: info: ControllerMpd::pushState Feb 24 19:25:17 siren volumio[3223]: info: CoreCommandRouter::servicePushState Feb 24 19:25:17 siren volumio[3223]: info: CorePlayQueue::getTrack 12 Feb 24 19:25:17 siren volumio[3223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":81,"duration":211,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3445 Kbps","isStreaming":false,"title":"18 - Five Short Minutes.flac","artist":null,"album":"IPRD","uri":"USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/18 - Five Short Minutes.flac","trackType":"flac"} Feb 24 19:25:17 siren volumio[3223]: verbose: CURRENT POSITION 12 Feb 24 19:25:17 siren volumio[3223]: info: CoreStateMachine::syncState stateService play Feb 24 19:25:17 siren volumio[3223]: info: CoreStateMachine::syncState currentStatus play Feb 24 19:25:17 siren volumio[3223]: info: Received an update from plugin. extracting info from payload Feb 24 19:25:17 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:17 siren volumio[3223]: info: CorePlayQueue::getTrack 12 Feb 24 19:25:17 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:17 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:17 siren volumio[3223]: info: CorePlayQueue::getTrack 12 Feb 24 19:25:17 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:17 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:17.857-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_PLAYING positionMs=81 volume= Feb 24 19:25:17 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:17.857-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_PLAYING positionMs=81 volume= Feb 24 19:25:17 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:17.857-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_PLAYING positionMs=81 volume= Feb 24 19:25:17 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:17.857-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/18 - Five Short Minutes.flac" title="18 - Five Short Minutes.flac" Feb 24 19:25:17 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:17.857-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/18 - Five Short Minutes.flac" title="18 - Five Short Minutes.flac" Feb 24 19:25:17 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:17.857-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/18 - Five Short Minutes.flac" title="18 - Five Short Minutes.flac" Feb 24 19:25:17 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:17.857-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_PLAYING positionMs=81 volume= Feb 24 19:25:17 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:17.857-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/18 - Five Short Minutes.flac" title="18 - Five Short Minutes.flac" Feb 24 19:25:17 siren volumio[3223]: info: ------------------------------ 11ms Feb 24 19:25:17 siren volumio[3223]: info: ------------------------------ 11ms Feb 24 19:25:17 siren volumio[3223]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 24 19:25:17 siren volumio[3223]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:25:17 siren volumio[3223]: info: ControllerMpd::pushState Feb 24 19:25:17 siren volumio[3223]: info: CoreCommandRouter::servicePushState Feb 24 19:25:17 siren volumio[3223]: info: CorePlayQueue::getTrack 12 Feb 24 19:25:17 siren volumio[3223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":81,"duration":211,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3445 Kbps","isStreaming":false,"title":"18 - Five Short Minutes.flac","artist":null,"album":"IPRD","uri":"USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/18 - Five Short Minutes.flac","trackType":"flac"} Feb 24 19:25:17 siren volumio[3223]: verbose: CURRENT POSITION 12 Feb 24 19:25:17 siren volumio[3223]: info: CoreStateMachine::syncState stateService play Feb 24 19:25:17 siren volumio[3223]: info: CoreStateMachine::syncState currentStatus play Feb 24 19:25:17 siren volumio[3223]: info: Received an update from plugin. extracting info from payload Feb 24 19:25:17 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:17 siren volumio[3223]: info: CorePlayQueue::getTrack 12 Feb 24 19:25:17 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:17 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:17 siren volumio[3223]: info: CorePlayQueue::getTrack 12 Feb 24 19:25:17 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:17 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:17.862-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_PLAYING positionMs=81 volume= Feb 24 19:25:17 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:17.862-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_PLAYING positionMs=81 volume= Feb 24 19:25:17 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:17.862-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/18 - Five Short Minutes.flac" title="18 - Five Short Minutes.flac" Feb 24 19:25:17 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:17.862-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/18 - Five Short Minutes.flac" title="18 - Five Short Minutes.flac" Feb 24 19:25:17 siren volumio[3223]: info: ------------------------------ 15ms Feb 24 19:25:27 siren volumio[3223]: info: Preload queue cleared Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::ClearQueue Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::stop Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::stPlaybackTimer Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::updateTrackBlock Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrackBlock Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 12 Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::serviceStop Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 12 Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::serviceStop Feb 24 19:25:27 siren volumio[3223]: info: ControllerMpd::stop Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::clearPlayQueue Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::saveQueue Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPushQueue Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::addQueueItems Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::addQueueItems Feb 24 19:25:27 siren volumio[3223]: info: Preload queue cleared Feb 24 19:25:27 siren volumio[3223]: info: Adding Item to queue: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/01 - Hey Tomorrow.flac Feb 24 19:25:27 siren volumio[3223]: info: Using cached record of: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/01 - Hey Tomorrow.flac Feb 24 19:25:27 siren volumio[3223]: info: Adding Item to queue: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/02 - Chain Gang Medley Chain GangHe Don't Love YouSearchin'.flac Feb 24 19:25:27 siren volumio[3223]: info: Using cached record of: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/02 - Chain Gang Medley Chain GangHe Don't Love YouSearchin'.flac Feb 24 19:25:27 siren volumio[3223]: info: Adding Item to queue: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/04 - Careful Man.flac Feb 24 19:25:27 siren volumio[3223]: info: Using cached record of: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/04 - Careful Man.flac Feb 24 19:25:27 siren volumio[3223]: info: Adding Item to queue: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/05 - These Dreams.flac Feb 24 19:25:27 siren volumio[3223]: info: Using cached record of: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/05 - These Dreams.flac Feb 24 19:25:27 siren volumio[3223]: info: Adding Item to queue: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/06 - It Doesn't Have to Be That Way.flac Feb 24 19:25:27 siren volumio[3223]: info: Using cached record of: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/06 - It Doesn't Have to Be That Way.flac Feb 24 19:25:27 siren volumio[3223]: info: Adding Item to queue: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/07 - Dreamin' Again.flac Feb 24 19:25:27 siren volumio[3223]: info: Using cached record of: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/07 - Dreamin' Again.flac Feb 24 19:25:27 siren volumio[3223]: info: Adding Item to queue: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/08 - Alabama Rain.flac Feb 24 19:25:27 siren volumio[3223]: info: Using cached record of: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/08 - Alabama Rain.flac Feb 24 19:25:27 siren volumio[3223]: info: Adding Item to queue: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/09 - A Good Time Man Like Me Ain't Got No Business (Singin' the Blues).flac Feb 24 19:25:27 siren volumio[3223]: info: Using cached record of: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/09 - A Good Time Man Like Me Ain't Got No Business (Singin' the Blues).flac Feb 24 19:25:27 siren volumio[3223]: info: Adding Item to queue: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/11 - Bad, Bad Leroy Brown.flac Feb 24 19:25:27 siren volumio[3223]: info: Using cached record of: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/11 - Bad, Bad Leroy Brown.flac Feb 24 19:25:27 siren volumio[3223]: info: Adding Item to queue: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/12 - One Less Set of Footsteps.flac Feb 24 19:25:27 siren volumio[3223]: info: Using cached record of: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/12 - One Less Set of Footsteps.flac Feb 24 19:25:27 siren volumio[3223]: info: Adding Item to queue: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/15 - I'll Have to Say I Love You in a Song.flac Feb 24 19:25:27 siren volumio[3223]: info: Using cached record of: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/15 - I'll Have to Say I Love You in a Song.flac Feb 24 19:25:27 siren volumio[3223]: info: Adding Item to queue: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/16 - I Got a Name.flac Feb 24 19:25:27 siren volumio[3223]: info: Using cached record of: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/16 - I Got a Name.flac Feb 24 19:25:27 siren volumio[3223]: info: Adding Item to queue: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/18 - Five Short Minutes.flac Feb 24 19:25:27 siren volumio[3223]: info: Using cached record of: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/18 - Five Short Minutes.flac Feb 24 19:25:27 siren volumio[3223]: info: Adding Item to queue: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/20 - The Hard Way Every Time.flac Feb 24 19:25:27 siren volumio[3223]: info: Using cached record of: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/20 - The Hard Way Every Time.flac Feb 24 19:25:27 siren volumio[3223]: info: Adding Item to queue: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac Feb 24 19:25:27 siren volumio[3223]: info: Using cached record of: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.441-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_STOPPED positionMs=0 volume= Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.441-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/18 - Five Short Minutes.flac" title="18 - Five Short Minutes.flac" Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPushQueue Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::saveQueue Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::updateTrackBlock Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrackBlock Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPlay Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::play index 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::addQueueItems Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::addQueueItems Feb 24 19:25:27 siren volumio[3223]: info: Preload queue cleared Feb 24 19:25:27 siren volumio[3223]: info: Adding Item to queue: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/22 - Workin' at the Car Wash Blues.flac Feb 24 19:25:27 siren volumio[3223]: info: Using cached record of: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/22 - Workin' at the Car Wash Blues.flac Feb 24 19:25:27 siren volumio[3223]: info: Adding Item to queue: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/23 - Lover's Cross.flac Feb 24 19:25:27 siren volumio[3223]: info: Using cached record of: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/23 - Lover's Cross.flac Feb 24 19:25:27 siren volumio[3223]: info: Adding Item to queue: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/24 - Salon and Saloon.flac Feb 24 19:25:27 siren volumio[3223]: info: Using cached record of: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/24 - Salon and Saloon.flac Feb 24 19:25:27 siren volumio[3223]: info: Adding Item to queue: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/26 - Track 26.flac Feb 24 19:25:27 siren volumio[3223]: info: Using cached record of: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/26 - Track 26.flac Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::stop Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPushQueue Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::saveQueue Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::play index undefined Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::updateTrackBlock Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrackBlock Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::startPlaybackTimer Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::clearAddPlayTracks USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand stop took 13 milliseconds Feb 24 19:25:27 siren volumio[3223]: info: Feb 24 19:25:27 siren volumio[3223]: ---------------------------- MPD announces state update: player Feb 24 19:25:27 siren volumio[3223]: info: ControllerMpd::getState Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand stop took 3 milliseconds Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 19:25:27 siren volumio[3223]: info: Feb 24 19:25:27 siren volumio[3223]: ---------------------------- MPD announces state update: player Feb 24 19:25:27 siren volumio[3223]: info: ControllerMpd::getState Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:25:27 siren volumio[3223]: info: Feb 24 19:25:27 siren volumio[3223]: ---------------------------- MPD announces state update: player Feb 24 19:25:27 siren volumio[3223]: info: ControllerMpd::getState Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:25:27 siren volumio[3223]: info: Feb 24 19:25:27 siren volumio[3223]: ---------------------------- MPD announces system playlist update Feb 24 19:25:27 siren volumio[3223]: info: Ignoring MPD Status Update Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand status took 1 milliseconds Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand clear took 1 milliseconds Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand status took 1 milliseconds Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand status took 0 milliseconds Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::parseState Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand add "USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac" Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::parseState Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::parseState Feb 24 19:25:27 siren volumio[3223]: info: ControllerMpd::pushState Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::servicePushState Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: 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} Feb 24 19:25:27 siren volumio[3223]: verbose: CURRENT POSITION 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::syncState stateService stop Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::syncState currentStatus stop Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:27 siren volumio[3223]: info: No code Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:27 siren volumio[3223]: info: ControllerMpd::pushState Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::servicePushState Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: 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} Feb 24 19:25:27 siren volumio[3223]: verbose: CURRENT POSITION 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::syncState stateService stop Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::syncState currentStatus stop Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:27 siren volumio[3223]: info: No code Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.456-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_STOPPED positionMs=0 volume= Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.456-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac" title="21 - Age.flac" Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.456-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_STOPPED positionMs=0 volume= Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.456-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_STOPPED positionMs=0 volume= Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.456-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_STOPPED positionMs=0 volume= Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.456-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac" title="21 - Age.flac" Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.456-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_STOPPED positionMs=0 volume= Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.456-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_STOPPED positionMs=0 volume= Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.456-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac" title="21 - Age.flac" Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.456-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac" title="21 - Age.flac" Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.456-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac" title="21 - Age.flac" Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.456-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac" title="21 - Age.flac" Feb 24 19:25:27 siren volumio[3223]: info: ------------------------------ 7ms Feb 24 19:25:27 siren volumio[3223]: info: ------------------------------ 6ms Feb 24 19:25:27 siren volumio[3223]: info: Feb 24 19:25:27 siren volumio[3223]: ---------------------------- MPD announces system playlist update Feb 24 19:25:27 siren volumio[3223]: info: Ignoring MPD Status Update Feb 24 19:25:27 siren volumio[3223]: info: Feb 24 19:25:27 siren volumio[3223]: ---------------------------- MPD announces system playlist update Feb 24 19:25:27 siren volumio[3223]: info: Ignoring MPD Status Update Feb 24 19:25:27 siren volumio[3223]: error: updateQueue error: null Feb 24 19:25:27 siren volumio[3223]: info: Feb 24 19:25:27 siren volumio[3223]: ---------------------------- MPD announces system playlist update Feb 24 19:25:27 siren volumio[3223]: info: Ignoring MPD Status Update Feb 24 19:25:27 siren volumio[3223]: info: ------------------------------ 7ms Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand add "USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac" took 7 milliseconds Feb 24 19:25:27 siren volumio[3223]: info: ------------------------------ 1ms Feb 24 19:25:27 siren volumio[3223]: info: ------------------------------ 1ms Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand play Feb 24 19:25:27 siren volumio[3223]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 24 19:25:27 siren volumio[3223]: info: ------------------------------ 8ms Feb 24 19:25:27 siren volumio[3223]: info: Feb 24 19:25:27 siren volumio[3223]: ---------------------------- MPD announces system playlist update Feb 24 19:25:27 siren volumio[3223]: info: Ignoring MPD Status Update Feb 24 19:25:27 siren volumio[3223]: info: Feb 24 19:25:27 siren volumio[3223]: ---------------------------- MPD announces system playlist update Feb 24 19:25:27 siren volumio[3223]: info: Ignoring MPD Status Update Feb 24 19:25:27 siren volumio[3223]: info: ------------------------------ 2ms Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand play took 2 milliseconds Feb 24 19:25:27 siren volumio[3223]: info: ------------------------------ 2ms Feb 24 19:25:27 siren volumio[3223]: info: ------------------------------ 2ms Feb 24 19:25:27 siren volumio[3223]: info: Feb 24 19:25:27 siren volumio[3223]: ---------------------------- MPD announces state update: player Feb 24 19:25:27 siren volumio[3223]: info: ControllerMpd::getState Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:25:27 siren volumio[3223]: info: Feb 24 19:25:27 siren volumio[3223]: ---------------------------- MPD announces state update: player Feb 24 19:25:27 siren volumio[3223]: info: ControllerMpd::getState Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:25:27 siren volumio[3223]: info: Feb 24 19:25:27 siren volumio[3223]: ---------------------------- MPD announces state update: player Feb 24 19:25:27 siren volumio[3223]: info: ControllerMpd::getState Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand status took 10 milliseconds Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::parseState Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:25:27 siren volumio[3223]: info: Feb 24 19:25:27 siren volumio[3223]: ---------------------------- MPD announces state update: player Feb 24 19:25:27 siren volumio[3223]: info: ControllerMpd::getState Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:25:27 siren volumio[3223]: info: Feb 24 19:25:27 siren volumio[3223]: ---------------------------- MPD announces state update: player Feb 24 19:25:27 siren volumio[3223]: info: ControllerMpd::getState Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand status took 11 milliseconds Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand status took 11 milliseconds Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::parseState Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::parseState Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:25:27 siren volumio[3223]: info: ControllerMpd::pushState Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::servicePushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"21 - Age.flac","artist":null,"album":"IPRD","uri":"USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac","trackType":"flac"} Feb 24 19:25:27 siren volumio[3223]: verbose: CURRENT POSITION 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::syncState stateService play Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::syncState currentStatus stop Feb 24 19:25:27 siren volumio[3223]: info: ------------------------------ 11ms Feb 24 19:25:27 siren volumio[3223]: info: Feb 24 19:25:27 siren volumio[3223]: ---------------------------- MPD announces state update: player Feb 24 19:25:27 siren volumio[3223]: info: ControllerMpd::getState Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand status took 2 milliseconds Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand status took 1 milliseconds Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::parseState Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::parseState Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:25:27 siren volumio[3223]: info: ControllerMpd::pushState Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::servicePushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20,"duration":227,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2766 Kbps","isStreaming":false,"title":"21 - Age.flac","artist":null,"album":"IPRD","uri":"USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac","trackType":"flac"} Feb 24 19:25:27 siren volumio[3223]: verbose: CURRENT POSITION 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::syncState stateService play Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::syncState currentStatus play Feb 24 19:25:27 siren volumio[3223]: info: Received an update from plugin. extracting info from payload Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:27 siren volumio[3223]: info: ControllerMpd::pushState Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::servicePushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34,"duration":227,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3075 Kbps","isStreaming":false,"title":"21 - Age.flac","artist":null,"album":"IPRD","uri":"USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac","trackType":"flac"} Feb 24 19:25:27 siren volumio[3223]: verbose: CURRENT POSITION 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::syncState stateService play Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::syncState currentStatus play Feb 24 19:25:27 siren volumio[3223]: info: Received an update from plugin. extracting info from payload Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.495-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_PLAYING positionMs=34 volume= Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.495-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac" title="21 - Age.flac" Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.495-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_PLAYING positionMs=20 volume= Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.495-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac" title="21 - Age.flac" Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.495-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_PLAYING positionMs=20 volume= Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.496-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac" title="21 - Age.flac" Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.496-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_PLAYING positionMs=34 volume= Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.496-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac" title="21 - Age.flac" Feb 24 19:25:27 siren volumio[3223]: info: ------------------------------ 18ms Feb 24 19:25:27 siren volumio[3223]: info: ------------------------------ 18ms Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand status took 8 milliseconds Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::parseState Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:25:27 siren volumio[3223]: info: ControllerMpd::pushState Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::servicePushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":432,"duration":227,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3197 Kbps","isStreaming":false,"title":"21 - Age.flac","artist":null,"album":"IPRD","uri":"USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac","trackType":"flac"} Feb 24 19:25:27 siren volumio[3223]: verbose: CURRENT POSITION 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::syncState stateService play Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::syncState currentStatus play Feb 24 19:25:27 siren volumio[3223]: info: Received an update from plugin. extracting info from payload Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:27 siren volumio[3223]: info: ControllerMpd::pushState Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::servicePushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":495,"duration":227,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3210 Kbps","isStreaming":false,"title":"21 - Age.flac","artist":null,"album":"IPRD","uri":"USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac","trackType":"flac"} Feb 24 19:25:27 siren volumio[3223]: verbose: CURRENT POSITION 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::syncState stateService play Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::syncState currentStatus play Feb 24 19:25:27 siren volumio[3223]: info: Received an update from plugin. extracting info from payload Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.501-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_PLAYING positionMs=432 volume= Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.501-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_PLAYING positionMs=432 volume= Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.501-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac" title="21 - Age.flac" Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.501-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac" title="21 - Age.flac" Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.501-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_PLAYING positionMs=495 volume= Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.501-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_PLAYING positionMs=495 volume= Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.501-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac" title="21 - Age.flac" Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.501-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac" title="21 - Age.flac" Feb 24 19:25:27 siren volumio[3223]: info: ------------------------------ 13ms Feb 24 19:25:27 siren volumio[3223]: info: ------------------------------ 12ms Feb 24 19:25:27 siren volumio[3223]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 24 19:25:27 siren volumio[3223]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:25:27 siren volumio[3223]: info: ControllerMpd::pushState Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::servicePushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":620,"duration":227,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3108 Kbps","isStreaming":false,"title":"21 - Age.flac","artist":null,"album":"IPRD","uri":"USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac","trackType":"flac"} Feb 24 19:25:27 siren volumio[3223]: verbose: CURRENT POSITION 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::syncState stateService play Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::syncState currentStatus play Feb 24 19:25:27 siren volumio[3223]: info: Received an update from plugin. extracting info from payload Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:27 siren volumio[3223]: info: CoreStateMachine::pushState Feb 24 19:25:27 siren volumio[3223]: info: CorePlayQueue::getTrack 14 Feb 24 19:25:27 siren volumio[3223]: info: CoreCommandRouter::volumioPushState Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.508-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_PLAYING positionMs=620 volume= Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.508-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" state=STATUS_PLAYING positionMs=620 volume= Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.508-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac" title="21 - Age.flac" Feb 24 19:25:27 siren volumio5-onboarding[2130]: time=2026-02-24T19:25:27.508-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.70:55370 @ 0xc00041c5a0" id="mnt/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary/21 - Age.flac" title="21 - Age.flac" Feb 24 19:25:27 siren volumio[3223]: info: ------------------------------ 18ms Feb 24 19:25:57 siren volumio[3223]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 19:25:57 siren volumio[3223]: info: CURURI: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce Feb 24 19:25:57 siren volumio[3223]: info: Preload queue cleared Feb 24 19:25:58 siren volumio[3223]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 19:25:58 siren volumio[3223]: info: CURURI: music-library/USB/dce0668f-5832-44fa-aa4b-86a82b2d8412/Music/MusicHD/Jim Croce/50th Anniversary Feb 24 19:25:58 siren volumio[3223]: info: Preload queue cleared Feb 24 19:26:25 siren volumio[3223]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 19:26:25 siren volumio[3223]: info: CURURI: artists:// Feb 24 19:26:25 siren volumio[3223]: info: Preload queue cleared Feb 24 19:26:29 siren volumio[3223]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 19:26:29 siren volumio[3223]: TypeError: Cannot read properties of null (reading 'slice') Feb 24 19:26:29 siren volumio[3223]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Feb 24 19:26:29 siren volumio[3223]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Feb 24 19:26:29 siren volumio[3223]: at Object.onceWrapper (node:events:629:26) Feb 24 19:26:29 siren volumio[3223]: at ClientRequest.emit (node:events:514:28) Feb 24 19:26:29 siren volumio[3223]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Feb 24 19:26:29 siren volumio[3223]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Feb 24 19:26:29 siren volumio[3223]: at TLSSocket.socketOnData (node:_http_client:535:22) Feb 24 19:26:29 siren volumio[3223]: at TLSSocket.emit (node:events:514:28) Feb 24 19:26:29 siren volumio[3223]: at addChunk (node:internal/streams/readable:343:12) Feb 24 19:26:29 siren volumio[3223]: at readableAddChunk (node:internal/streams/readable:316:9) Feb 24 19:26:29 siren volumio[3223]: at Readable.push (node:internal/streams/readable:253:10) Feb 24 19:26:29 siren volumio[3223]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Feb 24 19:26:29 siren volumio[3223]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 19:26:29 siren sudo[20762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-24 19:25' Feb 24 19:26:29 siren sudo[20762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"