-- Logs begin at Thu 2019-02-14 02:12:00 PST, end at Tue 2026-08-25 23:07:15 PDT. -- Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Preload queue cleared Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::ClearQueue Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::stop Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::stPlaybackTimer Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::updateTrackBlock Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrackBlock Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 10 Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::serviceStop Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 10 Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::serviceStop Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ControllerMpd::stop Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::clearPlayQueue Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::saveQueue Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushQueue Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::addQueueItems Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::addQueueItems Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Preload queue cleared Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/01 Rewrite.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/01 Rewrite.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/02 Sunday.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/02 Sunday.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/03 Breathe Me.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/03 Breathe Me.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/04 The Bully.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/04 The Bully.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif Aug 25 23:06:03 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:03.720-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=0 volume=100 Aug 25 23:06:03 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:03.721-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/11 Numb.aif" title="11 - Numb" Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushQueue Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::saveQueue Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::updateTrackBlock Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrackBlock Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPlay Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::play index 4 Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::addQueueItems Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::addQueueItems Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Preload queue cleared Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/06 Don't Bring Me Down.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/06 Don't Bring Me Down.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/07 Natale's Song.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/07 Natale's Song.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/08 Butterflies.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/08 Butterflies.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/09 Moon.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/09 Moon.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/10 The Church of What's Happening Now.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/10 The Church of What's Happening Now.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/11 Numb.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/11 Numb.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/12 Where I Belong.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/12 Where I Belong.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/13 Broken Biscuit.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/13 Broken Biscuit.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/14 Sea Shells.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/14 Sea Shells.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/15 Breathe Me.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/15 Breathe Me.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/16 Breathe Me.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/16 Breathe Me.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::stop Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushQueue Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::saveQueue Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::play index undefined Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::updateTrackBlock Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrackBlock Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::startPlaybackTimer Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::clearAddPlayTracks USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Aug 25 23:06:03 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: sendMpdCommand stop took 64 milliseconds Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: sendMpdCommand stop took 12 milliseconds Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand clear Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Aug 25 23:06:03 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Aug 25 23:06:03 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Aug 25 23:06:03 sw1xvdtiv volumio[674]: ---------------------------- MPD announces system playlist update Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Ignoring MPD Status Update Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 13 milliseconds Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: sendMpdCommand clear took 12 milliseconds Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 9 milliseconds Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 8 milliseconds Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand add "USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif" Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:03 sw1xvdtiv volumio[674]: 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} Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 4 Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService stop Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: No code Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:03 sw1xvdtiv volumio[674]: 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} Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 4 Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService stop Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: No code Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:03 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:03.833-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=3 volume=100 Aug 25 23:06:03 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:03.835-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif" title="5 - Sweet Potato" Aug 25 23:06:03 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:03.836-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=3 volume=100 Aug 25 23:06:03 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:03.837-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif" title="5 - Sweet Potato" Aug 25 23:06:03 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:03.837-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=3 volume=100 Aug 25 23:06:03 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:03.838-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=3 volume=100 Aug 25 23:06:03 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:03.839-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif" title="5 - Sweet Potato" Aug 25 23:06:03 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:03.840-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=3 volume=100 Aug 25 23:06:03 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:03.841-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif" title="5 - Sweet Potato" Aug 25 23:06:03 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:03.845-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=3 volume=100 Aug 25 23:06:03 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:03.846-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif" title="5 - Sweet Potato" Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ------------------------------ 81ms Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ------------------------------ 80ms Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Aug 25 23:06:03 sw1xvdtiv volumio[674]: ---------------------------- MPD announces system playlist update Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Ignoring MPD Status Update Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Aug 25 23:06:03 sw1xvdtiv volumio[674]: ---------------------------- MPD announces system playlist update Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Ignoring MPD Status Update Aug 25 23:06:03 sw1xvdtiv volumio[674]: error: updateQueue error: null Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Aug 25 23:06:03 sw1xvdtiv volumio[674]: ---------------------------- MPD announces system playlist update Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Ignoring MPD Status Update Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ------------------------------ 88ms Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 83 milliseconds Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: sendMpdCommand add "USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif" took 84 milliseconds Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ------------------------------ 13ms Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ------------------------------ 11ms Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand play Aug 25 23:06:03 sw1xvdtiv volumio[674]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ------------------------------ 107ms Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Aug 25 23:06:03 sw1xvdtiv volumio[674]: ---------------------------- MPD announces system playlist update Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Ignoring MPD Status Update Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Aug 25 23:06:03 sw1xvdtiv volumio[674]: ---------------------------- MPD announces system playlist update Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Ignoring MPD Status Update Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Aug 25 23:06:03 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ------------------------------ 34ms Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: sendMpdCommand play took 29 milliseconds Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ------------------------------ 26ms Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ------------------------------ 24ms Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Aug 25 23:06:03 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Aug 25 23:06:03 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Aug 25 23:06:03 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 17 milliseconds Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 13 milliseconds Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 10 milliseconds Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Aug 25 23:06:03 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Aug 25 23:06:03 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 32 milliseconds Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 28 milliseconds Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 30 milliseconds Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 29 milliseconds Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 23 milliseconds Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":241,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Potato","artist":"Sia","album":"Colour the Small One [16 Tracks]","uri":"USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif","trackType":"aif"} Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 4 Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService play Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":241,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Potato","artist":"Sia","album":"Colour the Small One [16 Tracks]","uri":"USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif","trackType":"aif"} Aug 25 23:06:03 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 4 Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService play Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus play Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: Received an update from plugin. extracting info from payload Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:03 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:04 sw1xvdtiv volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":241,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Potato","artist":"Sia","album":"Colour the Small One [16 Tracks]","uri":"USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif","trackType":"aif"} Aug 25 23:06:04 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 4 Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService play Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus play Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: Received an update from plugin. extracting info from payload Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: ------------------------------ 107ms Aug 25 23:06:04 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:04.013-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=846 volume=100 Aug 25 23:06:04 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:04.014-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif" title="5 - Sweet Potato" Aug 25 23:06:04 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:04.016-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=846 volume=100 Aug 25 23:06:04 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:04.017-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif" title="5 - Sweet Potato" Aug 25 23:06:04 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:04.019-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=846 volume=100 Aug 25 23:06:04 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:04.020-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif" title="5 - Sweet Potato" Aug 25 23:06:04 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:04.022-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=846 volume=100 Aug 25 23:06:04 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:04.023-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif" title="5 - Sweet Potato" Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: ------------------------------ 119ms Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: ------------------------------ 116ms Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 82 milliseconds Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 62 milliseconds Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 59 milliseconds Aug 25 23:06:04 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:06:04 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:06:04 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:06:04 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:04 sw1xvdtiv volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":241,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Potato","artist":"Sia","album":"Colour the Small One [16 Tracks]","uri":"USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif","trackType":"aif"} Aug 25 23:06:04 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 4 Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService play Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus play Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: Received an update from plugin. extracting info from payload Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:04 sw1xvdtiv volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":241,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Potato","artist":"Sia","album":"Colour the Small One [16 Tracks]","uri":"USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif","trackType":"aif"} Aug 25 23:06:04 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 4 Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService play Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus play Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: Received an update from plugin. extracting info from payload Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:04 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:04.065-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=846 volume=100 Aug 25 23:06:04 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:04.066-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif" title="5 - Sweet Potato" Aug 25 23:06:04 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:04.067-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=846 volume=100 Aug 25 23:06:04 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:04.068-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif" title="5 - Sweet Potato" Aug 25 23:06:04 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:04.073-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=846 volume=100 Aug 25 23:06:04 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:04.075-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif" title="5 - Sweet Potato" Aug 25 23:06:04 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:04.075-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=846 volume=100 Aug 25 23:06:04 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:04.077-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif" title="5 - Sweet Potato" Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: ------------------------------ 161ms Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: ------------------------------ 138ms Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 77 milliseconds Aug 25 23:06:04 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:04 sw1xvdtiv volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":241,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Potato","artist":"Sia","album":"Colour the Small One [16 Tracks]","uri":"USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif","trackType":"aif"} Aug 25 23:06:04 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 4 Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService play Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus play Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: Received an update from plugin. extracting info from payload Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:04 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:04.143-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=846 volume=100 Aug 25 23:06:04 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:04.144-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif" title="5 - Sweet Potato" Aug 25 23:06:04 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:04.144-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=846 volume=100 Aug 25 23:06:04 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:04.145-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif" title="5 - Sweet Potato" Aug 25 23:06:04 sw1xvdtiv volumio[674]: info: ------------------------------ 199ms Aug 25 23:06:09 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:09.909-07:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.70:60401 @ 0x2cc4120" latency=114.333935ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 25 23:06:09 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:09.943-07:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.70:60401 @ 0x2cc4120" latency=114.696592ms timeout=10s Aug 25 23:06:09 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:09.955-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.70:60401 @ 0x2cc4120" latency=114.696592ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=10.712359ms Aug 25 23:06:09 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:09.991-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.70:60401 @ 0x2cc4120" latency=114.696592ms timeout=10s endpoint=https://google.com duration=46.468851ms Aug 25 23:06:09 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:09.998-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.70:60401 @ 0x2cc4120" latency=114.696592ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=53.914085ms Aug 25 23:06:10 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:10.012-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.70:60401 @ 0x2cc4120" latency=114.696592ms timeout=10s endpoint=https://securetoken.googleapis.com duration=68.360286ms Aug 25 23:06:10 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:10.021-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.70:60401 @ 0x2cc4120" latency=114.696592ms timeout=10s endpoint=https://functions.volumio.cloud duration=76.142811ms Aug 25 23:06:10 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:10.023-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.70:60401 @ 0x2cc4120" latency=114.696592ms timeout=10s endpoint=https://functions.volumio.cloud duration=77.584577ms Aug 25 23:06:10 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:10.029-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.70:60401 @ 0x2cc4120" latency=114.696592ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=83.424349ms Aug 25 23:06:10 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:10.038-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.70:60401 @ 0x2cc4120" latency=114.696592ms timeout=10s endpoint=https://database.volumio.cloud duration=92.356454ms Aug 25 23:06:10 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:10.057-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.70:60401 @ 0x2cc4120" latency=114.696592ms timeout=10s endpoint=https://www.googleapis.com duration=112.192688ms Aug 25 23:06:10 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:10.098-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.70:60401 @ 0x2cc4120" latency=114.696592ms timeout=10s endpoint=http://pushupdates.volumio.org duration=151.094585ms Aug 25 23:06:10 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:10.102-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.70:60401 @ 0x2cc4120" latency=114.696592ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=157.23972ms Aug 25 23:06:10 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:10.122-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.70:60401 @ 0x2cc4120" latency=114.696592ms timeout=10s endpoint=http://plugins.volumio.org duration=175.961374ms Aug 25 23:06:10 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:10.210-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.70:60401 @ 0x2cc4120" latency=114.696592ms timeout=10s endpoint=http://cddb.volumio.org duration=263.490813ms Aug 25 23:06:11 sw1xvdtiv sudo[1943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 23:06:11 sw1xvdtiv sudo[1943]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 23:06:11 sw1xvdtiv sudo[1943]: pam_unix(sudo:session): session closed for user root Aug 25 23:06:11 sw1xvdtiv sudo[1945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 23:06:11 sw1xvdtiv sudo[1945]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 23:06:11 sw1xvdtiv sudo[1945]: pam_unix(sudo:session): session closed for user root Aug 25 23:06:11 sw1xvdtiv volumio[674]: verbose: New Socket.io Connection to 192.168.1.56 from 192.168.1.70 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 6 Aug 25 23:06:11 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 25 23:06:12 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 25 23:06:12 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioGetVisibleSources Aug 25 23:06:12 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 23:06:12 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioGetState Aug 25 23:06:12 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:12 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 25 23:06:12 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 25 23:06:12 sw1xvdtiv volumio[674]: info: Received Get System Info Aug 25 23:06:12 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 23:06:12 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 23:06:12 sw1xvdtiv volumio[674]: info: Discovery: Getting this device information Aug 25 23:06:12 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioGetState Aug 25 23:06:12 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:12 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 23:06:12 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioGetState Aug 25 23:06:12 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:12 sw1xvdtiv volumio[674]: info: Listing playlists Aug 25 23:06:12 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 25 23:06:12 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 23:06:12 sw1xvdtiv sudo[1955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 23:06:12 sw1xvdtiv sudo[1955]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 23:06:12 sw1xvdtiv sudo[1955]: pam_unix(sudo:session): session closed for user root Aug 25 23:06:12 sw1xvdtiv sudo[1957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 23:06:12 sw1xvdtiv sudo[1957]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 23:06:12 sw1xvdtiv sudo[1957]: pam_unix(sudo:session): session closed for user root Aug 25 23:06:13 sw1xvdtiv volumio[674]: verbose: New Socket.io Connection to 192.168.1.56 from 192.168.1.70 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7 Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioGetVisibleSources Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioGetState Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioGetQueue Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::getQueue Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getQueue Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: Listing playlists Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: Received Get System Info Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: Discovery: Getting this device information Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioGetState Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioGetState Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 25 23:06:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 23:06:14 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 23:06:14 sw1xvdtiv volumio[674]: info: Received Get System Info Aug 25 23:06:14 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 23:06:14 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 23:06:14 sw1xvdtiv volumio[674]: info: Discovery: Getting this device information Aug 25 23:06:14 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioGetState Aug 25 23:06:14 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:14 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 23:06:15 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 25 23:06:15 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 23:06:15 sw1xvdtiv volumio[674]: info: Received Get System Info Aug 25 23:06:15 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 23:06:15 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 23:06:15 sw1xvdtiv volumio[674]: info: Discovery: Getting this device information Aug 25 23:06:15 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioGetState Aug 25 23:06:15 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:15 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: CURURI: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Preload queue cleared Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Preloading song: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/01 Pictures.aif Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Preloading song: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/01 Pictures.m4a Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Preloading song: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/02 Don't Bring Me Down (Live).m4a Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Preloading song: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/02 Don't Bring Me Down.aif Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Preloading song: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/03 Destiny (Live).m4a Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Preloading song: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/03 Destiny.aif Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Preloading song: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/04 Blow It All Away (Live).m4a Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Preloading song: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/04 Blow It All Away.aif Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Preloading song: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/05 Lentil (Live).m4a Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Preloading song: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/05 Lentil.aif Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Preloading song: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb (Live).m4a Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Preloading song: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Preloading song: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/07 I Go To Sleep (Live).m4a Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Preloading song: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/07 I Go to Sleep.aif Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Preloading song: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/08 Breathe Me (Live).m4a Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Preloading song: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/08 Breathe Me.aif Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Preloading song: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/09 Distractions (Live).m4a Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Preloading song: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/09 Distractions.aif Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Exploding uri music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/01 Pictures.aif in service mpd Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: ALBUMART /albumart?cacheid=491&web=Sia/Lady%20Croissant/extralarge&path=%2Fmnt%2FUSB%2FExtreme_SSD%2FMusic%2FSia%2FLady%20Croissant%2F01%20Pictures.aif&metadata=false Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: URI /mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/01 Pictures.aif Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Exploding uri music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/01 Pictures.m4a in service mpd Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: ALBUMART /albumart?cacheid=491&web=Sia/Lady%20Croissant/extralarge&path=%2Fmnt%2FUSB%2FExtreme_SSD%2FMusic%2FSia%2FLady%20Croissant%2F01%20Pictures.m4a&metadata=false Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: URI /mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/01 Pictures.m4a Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Exploding uri music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/02 Don't Bring Me Down (Live).m4a in service mpd Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: ALBUMART /albumart?cacheid=491&web=Sia/Lady%20Croissant/extralarge&path=%2Fmnt%2FUSB%2FExtreme_SSD%2FMusic%2FSia%2FLady%20Croissant%2F02%20Don't%20Bring%20Me%20Down%20(Live).m4a&metadata=false Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: URI /mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/02 Don't Bring Me Down (Live).m4a Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Exploding uri music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/02 Don't Bring Me Down.aif in service mpd Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: ALBUMART /albumart?cacheid=491&web=Sia/Lady%20Croissant/extralarge&path=%2Fmnt%2FUSB%2FExtreme_SSD%2FMusic%2FSia%2FLady%20Croissant%2F02%20Don't%20Bring%20Me%20Down.aif&metadata=false Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: URI /mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/02 Don't Bring Me Down.aif Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Exploding uri music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/03 Destiny (Live).m4a in service mpd Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: ALBUMART /albumart?cacheid=491&web=Sia/Lady%20Croissant/extralarge&path=%2Fmnt%2FUSB%2FExtreme_SSD%2FMusic%2FSia%2FLady%20Croissant%2F03%20Destiny%20(Live).m4a&metadata=false Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: URI /mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/03 Destiny (Live).m4a Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Exploding uri music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/03 Destiny.aif in service mpd Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: ALBUMART /albumart?cacheid=491&web=Sia/Lady%20Croissant/extralarge&path=%2Fmnt%2FUSB%2FExtreme_SSD%2FMusic%2FSia%2FLady%20Croissant%2F03%20Destiny.aif&metadata=false Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: URI /mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/03 Destiny.aif Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Exploding uri music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/04 Blow It All Away (Live).m4a in service mpd Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: ALBUMART /albumart?cacheid=491&web=Sia/Lady%20Croissant/extralarge&path=%2Fmnt%2FUSB%2FExtreme_SSD%2FMusic%2FSia%2FLady%20Croissant%2F04%20Blow%20It%20All%20Away%20(Live).m4a&metadata=false Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: URI /mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/04 Blow It All Away (Live).m4a Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Exploding uri music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/04 Blow It All Away.aif in service mpd Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: ALBUMART /albumart?cacheid=491&web=Sia/Lady%20Croissant/extralarge&path=%2Fmnt%2FUSB%2FExtreme_SSD%2FMusic%2FSia%2FLady%20Croissant%2F04%20Blow%20It%20All%20Away.aif&metadata=false Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: URI /mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/04 Blow It All Away.aif Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Exploding uri music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/05 Lentil (Live).m4a in service mpd Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: ALBUMART /albumart?cacheid=491&web=Sia/Lady%20Croissant/extralarge&path=%2Fmnt%2FUSB%2FExtreme_SSD%2FMusic%2FSia%2FLady%20Croissant%2F05%20Lentil%20(Live).m4a&metadata=false Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: URI /mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/05 Lentil (Live).m4a Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Exploding uri music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/05 Lentil.aif in service mpd Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: ALBUMART /albumart?cacheid=491&web=Sia/Lady%20Croissant/extralarge&path=%2Fmnt%2FUSB%2FExtreme_SSD%2FMusic%2FSia%2FLady%20Croissant%2F05%20Lentil.aif&metadata=false Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: URI /mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/05 Lentil.aif Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Exploding uri music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb (Live).m4a in service mpd Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: ALBUMART /albumart?cacheid=491&web=Sia/Lady%20Croissant/extralarge&path=%2Fmnt%2FUSB%2FExtreme_SSD%2FMusic%2FSia%2FLady%20Croissant%2F06%20Numb%20(Live).m4a&metadata=false Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: URI /mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb (Live).m4a Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Exploding uri music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif in service mpd Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: ALBUMART /albumart?cacheid=491&web=Sia/Lady%20Croissant/extralarge&path=%2Fmnt%2FUSB%2FExtreme_SSD%2FMusic%2FSia%2FLady%20Croissant%2F06%20Numb.aif&metadata=false Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: URI /mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Exploding uri music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/07 I Go To Sleep (Live).m4a in service mpd Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: ALBUMART /albumart?cacheid=491&web=Sia/Lady%20Croissant/extralarge&path=%2Fmnt%2FUSB%2FExtreme_SSD%2FMusic%2FSia%2FLady%20Croissant%2F07%20I%20Go%20To%20Sleep%20(Live).m4a&metadata=false Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: URI /mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/07 I Go To Sleep (Live).m4a Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Exploding uri music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/07 I Go to Sleep.aif in service mpd Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: ALBUMART /albumart?cacheid=491&web=Sia/Lady%20Croissant/extralarge&path=%2Fmnt%2FUSB%2FExtreme_SSD%2FMusic%2FSia%2FLady%20Croissant%2F07%20I%20Go%20to%20Sleep.aif&metadata=false Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: URI /mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/07 I Go to Sleep.aif Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Exploding uri music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/08 Breathe Me (Live).m4a in service mpd Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: ALBUMART /albumart?cacheid=491&web=Sia/Lady%20Croissant/extralarge&path=%2Fmnt%2FUSB%2FExtreme_SSD%2FMusic%2FSia%2FLady%20Croissant%2F08%20Breathe%20Me%20(Live).m4a&metadata=false Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: URI /mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/08 Breathe Me (Live).m4a Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: Exploding uri music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/08 Breathe Me.aif in service mpd Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: ALBUMART /albumart?cacheid=491&web=Sia/Lady%20Croissant/extralarge&path=%2Fmnt%2FUSB%2FExtreme_SSD%2FMusic%2FSia%2FLady%20Croissant%2F08%20Breathe%20Me.aif&metadata=false Aug 25 23:06:21 sw1xvdtiv volumio[674]: info: URI /mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/08 Breathe Me.aif Aug 25 23:06:22 sw1xvdtiv volumio[674]: info: Exploding uri music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/09 Distractions (Live).m4a in service mpd Aug 25 23:06:22 sw1xvdtiv volumio[674]: info: ALBUMART /albumart?cacheid=491&web=Sia/Lady%20Croissant/extralarge&path=%2Fmnt%2FUSB%2FExtreme_SSD%2FMusic%2FSia%2FLady%20Croissant%2F09%20Distractions%20(Live).m4a&metadata=false Aug 25 23:06:22 sw1xvdtiv volumio[674]: info: URI /mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/09 Distractions (Live).m4a Aug 25 23:06:22 sw1xvdtiv volumio[674]: info: Exploding uri music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/09 Distractions.aif in service mpd Aug 25 23:06:22 sw1xvdtiv volumio[674]: info: ALBUMART /albumart?cacheid=491&web=Sia/Lady%20Croissant/extralarge&path=%2Fmnt%2FUSB%2FExtreme_SSD%2FMusic%2FSia%2FLady%20Croissant%2F09%20Distractions.aif&metadata=false Aug 25 23:06:22 sw1xvdtiv volumio[674]: info: URI /mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/09 Distractions.aif Aug 25 23:06:23 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 25 23:06:31 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 23:06:31 sw1xvdtiv volumio[674]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 25 23:06:31 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 25 23:06:31 sw1xvdtiv volumio[674]: info: Received Get System Version Aug 25 23:06:31 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 23:06:31 sw1xvdtiv volumio[674]: info: Received Get System Info Aug 25 23:06:31 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 23:06:31 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 23:06:31 sw1xvdtiv volumio[674]: info: Discovery: Getting this device information Aug 25 23:06:31 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioGetState Aug 25 23:06:31 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:31 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Preload queue cleared Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::ClearQueue Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::stop Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::stPlaybackTimer Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::updateTrackBlock Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrackBlock Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::serviceStop Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 4 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::serviceStop Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ControllerMpd::stop Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::clearPlayQueue Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::saveQueue Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushQueue Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::addQueueItems Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::addQueueItems Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Preload queue cleared Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/01 Pictures.aif Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/01 Pictures.aif Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/01 Pictures.m4a Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/01 Pictures.m4a Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/02 Don't Bring Me Down (Live).m4a Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/02 Don't Bring Me Down (Live).m4a Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/02 Don't Bring Me Down.aif Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/02 Don't Bring Me Down.aif Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/03 Destiny (Live).m4a Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/03 Destiny (Live).m4a Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/03 Destiny.aif Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/03 Destiny.aif Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/04 Blow It All Away (Live).m4a Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/04 Blow It All Away (Live).m4a Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/04 Blow It All Away.aif Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/04 Blow It All Away.aif Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/05 Lentil (Live).m4a Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/05 Lentil (Live).m4a Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/05 Lentil.aif Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/05 Lentil.aif Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb (Live).m4a Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb (Live).m4a Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.322-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=0 volume=100 Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.323-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Colour the Small One [16 Tracks]/05 Sweet Potato.aif" title="5 - Sweet Potato" Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushQueue Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::saveQueue Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::updateTrackBlock Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrackBlock Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPlay Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::play index 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::addQueueItems Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::addQueueItems Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Preload queue cleared Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/07 I Go To Sleep (Live).m4a Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/07 I Go To Sleep (Live).m4a Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/07 I Go to Sleep.aif Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/07 I Go to Sleep.aif Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/08 Breathe Me (Live).m4a Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/08 Breathe Me (Live).m4a Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/08 Breathe Me.aif Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/08 Breathe Me.aif Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/09 Distractions (Live).m4a Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/09 Distractions (Live).m4a Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/09 Distractions.aif Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Sia/Lady Croissant/09 Distractions.aif Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::stop Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushQueue Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::saveQueue Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::play index undefined Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::updateTrackBlock Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrackBlock Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::startPlaybackTimer Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::clearAddPlayTracks USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Aug 25 23:06:35 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand stop took 99 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand stop took 18 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand clear Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Aug 25 23:06:35 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Aug 25 23:06:35 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Aug 25 23:06:35 sw1xvdtiv volumio[674]: ---------------------------- MPD announces system playlist update Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Ignoring MPD Status Update Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 17 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand clear took 17 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 14 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 12 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand add "USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif" Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: 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} Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService stop Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: No code Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: 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} Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService stop Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: No code Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.471-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=5 volume=100 Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.472-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif" title="6 - Numb" Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.472-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=5 volume=100 Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.473-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif" title="6 - Numb" Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.473-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=5 volume=100 Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.476-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=5 volume=100 Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.477-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=5 volume=100 Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.478-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif" title="6 - Numb" Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.478-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=5 volume=100 Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.479-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif" title="6 - Numb" Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.476-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif" title="6 - Numb" Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.481-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif" title="6 - Numb" Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ------------------------------ 86ms Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ------------------------------ 85ms Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Aug 25 23:06:35 sw1xvdtiv volumio[674]: ---------------------------- MPD announces system playlist update Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Ignoring MPD Status Update Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Aug 25 23:06:35 sw1xvdtiv volumio[674]: ---------------------------- MPD announces system playlist update Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Ignoring MPD Status Update Aug 25 23:06:35 sw1xvdtiv volumio[674]: error: updateQueue error: null Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Aug 25 23:06:35 sw1xvdtiv volumio[674]: ---------------------------- MPD announces system playlist update Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Ignoring MPD Status Update Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ------------------------------ 94ms Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 88 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand add "USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif" took 89 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ------------------------------ 17ms Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ------------------------------ 16ms Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand play Aug 25 23:06:35 sw1xvdtiv volumio[674]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ------------------------------ 118ms Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Aug 25 23:06:35 sw1xvdtiv volumio[674]: ---------------------------- MPD announces system playlist update Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Ignoring MPD Status Update Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Aug 25 23:06:35 sw1xvdtiv volumio[674]: ---------------------------- MPD announces system playlist update Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Ignoring MPD Status Update Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Aug 25 23:06:35 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ------------------------------ 34ms Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand play took 30 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ------------------------------ 27ms Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ------------------------------ 25ms Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Aug 25 23:06:35 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Aug 25 23:06:35 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Aug 25 23:06:35 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 15 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 12 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 10 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Aug 25 23:06:35 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Aug 25 23:06:35 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 23 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 18 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 18 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 12 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 8 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":266,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Numb","artist":"Sia","album":"Lady Croissant","uri":"USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif","trackType":"aif"} Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService play Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":266,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Numb","artist":"Sia","album":"Lady Croissant","uri":"USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif","trackType":"aif"} Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService play Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus play Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Received an update from plugin. extracting info from payload Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":266,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Numb","artist":"Sia","album":"Lady Croissant","uri":"USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif","trackType":"aif"} Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService play Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus play Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Received an update from plugin. extracting info from payload Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ------------------------------ 115ms Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.667-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=846 volume=100 Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.668-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif" title="6 - Numb" Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.671-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=846 volume=100 Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.672-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif" title="6 - Numb" Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.675-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=846 volume=100 Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.676-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif" title="6 - Numb" Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.678-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=846 volume=100 Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.679-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif" title="6 - Numb" Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ------------------------------ 135ms Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ------------------------------ 133ms Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 99 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 97 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 96 milliseconds Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":266,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Numb","artist":"Sia","album":"Lady Croissant","uri":"USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif","trackType":"aif"} Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService play Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus play Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Received an update from plugin. extracting info from payload Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":266,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Numb","artist":"Sia","album":"Lady Croissant","uri":"USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif","trackType":"aif"} Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService play Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus play Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Received an update from plugin. extracting info from payload Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":266,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Numb","artist":"Sia","album":"Lady Croissant","uri":"USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif","trackType":"aif"} Aug 25 23:06:35 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService play Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus play Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: Received an update from plugin. extracting info from payload Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.744-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=846 volume=100 Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.745-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif" title="6 - Numb" Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.745-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=846 volume=100 Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.747-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif" title="6 - Numb" Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.747-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=846 volume=100 Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.748-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif" title="6 - Numb" Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.748-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=846 volume=100 Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.749-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=846 volume=100 Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.749-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif" title="6 - Numb" Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.750-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif" title="6 - Numb" Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.751-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=846 volume=100 Aug 25 23:06:35 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:06:35.752-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif" title="6 - Numb" Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ------------------------------ 213ms Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ------------------------------ 201ms Aug 25 23:06:35 sw1xvdtiv volumio[674]: info: ------------------------------ 199ms Aug 25 23:07:03 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 25 23:07:03 sw1xvdtiv volumio[674]: info: CURURI: music-library/USB/Extreme_SSD/Music/Aphex twin Aug 25 23:07:03 sw1xvdtiv volumio[674]: info: Preload queue cleared Aug 25 23:07:07 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 25 23:07:07 sw1xvdtiv volumio[674]: info: CURURI: music-library/USB/Extreme_SSD/Music/Aphex twin/Unknown Album Aug 25 23:07:08 sw1xvdtiv volumio[674]: info: Preload queue cleared Aug 25 23:07:08 sw1xvdtiv volumio[674]: info: Preloading song: music-library/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3 Aug 25 23:07:08 sw1xvdtiv volumio[674]: info: Exploding uri music-library/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3 in service mpd Aug 25 23:07:08 sw1xvdtiv volumio[674]: info: ALBUMART /albumart?cacheid=491&web=Aphex%20twin//extralarge&path=%2Fmnt%2FUSB%2FExtreme_SSD%2FMusic%2FAphex%20twin%2FUnknown%20Album%2F01%20Let%20My%20Fish%20Loose.mp3&metadata=false Aug 25 23:07:08 sw1xvdtiv volumio[674]: info: URI /mnt/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3 Aug 25 23:07:12 sw1xvdtiv volumio[674]: info: Preload queue cleared Aug 25 23:07:12 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 25 23:07:12 sw1xvdtiv volumio[674]: info: CoreStateMachine::ClearQueue Aug 25 23:07:12 sw1xvdtiv volumio[674]: info: CoreStateMachine::stop Aug 25 23:07:12 sw1xvdtiv volumio[674]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 23:07:12 sw1xvdtiv volumio[674]: info: CoreStateMachine::stPlaybackTimer Aug 25 23:07:12 sw1xvdtiv volumio[674]: info: CoreStateMachine::updateTrackBlock Aug 25 23:07:12 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrackBlock Aug 25 23:07:12 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:07:12 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:07:12 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:07:12 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::serviceStop Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 11 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::serviceStop Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ControllerMpd::stop Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::clearPlayQueue Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::saveQueue Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushQueue Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::addQueueItems Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::addQueueItems Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Preload queue cleared Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Adding Item to queue: music-library/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Using cached record of: music-library/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3 Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.021-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=0 volume=100 Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.022-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Sia/Lady Croissant/06 Numb.aif" title="6 - Numb" Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushQueue Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::saveQueue Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::updateTrackBlock Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrackBlock Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPlay Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::play index 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::stop Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::play index undefined Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::startPlaybackTimer Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::clearAddPlayTracks USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3 Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Aug 25 23:07:13 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand stop took 43 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand stop took 10 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand clear Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Aug 25 23:07:13 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Aug 25 23:07:13 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Aug 25 23:07:13 sw1xvdtiv volumio[674]: ---------------------------- MPD announces system playlist update Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Ignoring MPD Status Update Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 15 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand clear took 13 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 10 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 7 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand add "USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3" Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: 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} Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService stop Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: No code Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: 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} Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService stop Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: No code Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.115-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=0 volume=100 Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.116-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3" title="1 - Let My Fish Loose" Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.116-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=0 volume=100 Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.117-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3" title="1 - Let My Fish Loose" Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.118-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=0 volume=100 Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.119-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3" title="1 - Let My Fish Loose" Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.119-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=0 volume=100 Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.120-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3" title="1 - Let My Fish Loose" Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.121-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=0 volume=100 Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.122-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3" title="1 - Let My Fish Loose" Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.122-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_STOPPED positionMs=0 volume=100 Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.123-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3" title="1 - Let My Fish Loose" Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ------------------------------ 76ms Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ------------------------------ 74ms Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Aug 25 23:07:13 sw1xvdtiv volumio[674]: ---------------------------- MPD announces system playlist update Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Ignoring MPD Status Update Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Aug 25 23:07:13 sw1xvdtiv volumio[674]: ---------------------------- MPD announces system playlist update Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Ignoring MPD Status Update Aug 25 23:07:13 sw1xvdtiv volumio[674]: error: updateQueue error: null Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Aug 25 23:07:13 sw1xvdtiv volumio[674]: ---------------------------- MPD announces system playlist update Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Ignoring MPD Status Update Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ------------------------------ 80ms Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 75 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand add "USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3" took 75 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ------------------------------ 11ms Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ------------------------------ 9ms Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand play Aug 25 23:07:13 sw1xvdtiv volumio[674]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ------------------------------ 99ms Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Aug 25 23:07:13 sw1xvdtiv volumio[674]: ---------------------------- MPD announces system playlist update Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Ignoring MPD Status Update Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Aug 25 23:07:13 sw1xvdtiv volumio[674]: ---------------------------- MPD announces system playlist update Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Ignoring MPD Status Update Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ------------------------------ 37ms Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand play took 32 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ------------------------------ 31ms Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ------------------------------ 28ms Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Aug 25 23:07:13 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Aug 25 23:07:13 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Aug 25 23:07:13 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 22 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Aug 25 23:07:13 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 26 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 24 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Let My Fish Loose","artist":"Aphex twin","album":null,"uri":"USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3","trackType":"mp3"} Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService play Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus stop Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ------------------------------ 47ms Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Aug 25 23:07:13 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Aug 25 23:07:13 sw1xvdtiv volumio[674]: ---------------------------- MPD announces state update: player Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ControllerMpd::getState Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand status Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 27 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 22 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 22 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 10 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Let My Fish Loose","artist":"Aphex twin","album":null,"uri":"USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3","trackType":"mp3"} Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService play Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus play Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Received an update from plugin. extracting info from payload Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Let My Fish Loose","artist":"Aphex twin","album":null,"uri":"USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3","trackType":"mp3"} Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService play Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus play Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Received an update from plugin. extracting info from payload Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.307-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.308-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3" title="1 - Let My Fish Loose" Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.309-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.310-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.311-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.312-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3" title="1 - Let My Fish Loose" Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.314-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3" title="1 - Let My Fish Loose" Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.315-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3" title="1 - Let My Fish Loose" Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ------------------------------ 118ms Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ------------------------------ 117ms Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand status took 74 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 67 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 65 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseState Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Let My Fish Loose","artist":"Aphex twin","album":null,"uri":"USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3","trackType":"mp3"} Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService play Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus play Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Received an update from plugin. extracting info from payload Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Let My Fish Loose","artist":"Aphex twin","album":null,"uri":"USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3","trackType":"mp3"} Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService play Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus play Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Received an update from plugin. extracting info from payload Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.361-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=857 volume=100 Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.362-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=857 volume=100 Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.362-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3" title="1 - Let My Fish Loose" Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.363-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3" title="1 - Let My Fish Loose" Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.364-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=857 volume=100 Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.365-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3" title="1 - Let My Fish Loose" Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.367-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=857 volume=100 Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.368-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3" title="1 - Let My Fish Loose" Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ------------------------------ 160ms Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ------------------------------ 141ms Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: sendMpdCommand playlistinfo took 87 milliseconds Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: ControllerMpd::parseTrackInfo Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ControllerMpd::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::servicePushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":287,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Let My Fish Loose","artist":"Aphex twin","album":null,"uri":"USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3","trackType":"mp3"} Aug 25 23:07:13 sw1xvdtiv volumio[674]: verbose: CURRENT POSITION 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState stateService play Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::syncState currentStatus play Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: Received an update from plugin. extracting info from payload Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreStateMachine::pushState Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CorePlayQueue::getTrack 0 Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: CoreCommandRouter::volumioPushState Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.438-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=857 volume=100 Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.439-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" state=STATUS_PLAYING positionMs=857 volume=100 Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.439-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3" title="1 - Let My Fish Loose" Aug 25 23:07:13 sw1xvdtiv volumio5-onboarding[1005]: time=2026-08-25T23:07:13.439-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.70:60401 @ 0x2cc4120" id="mnt/USB/Extreme_SSD/Music/Aphex twin/Unknown Album/01 Let My Fish Loose.mp3" title="1 - Let My Fish Loose" Aug 25 23:07:13 sw1xvdtiv volumio[674]: info: ------------------------------ 216ms Aug 25 23:07:14 sw1xvdtiv volumio[674]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 23:07:14 sw1xvdtiv volumio[674]: Error: connect ECONNREFUSED 158.69.210.98:443 Aug 25 23:07:14 sw1xvdtiv volumio[674]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 25 23:07:14 sw1xvdtiv volumio[674]: errno: -111, Aug 25 23:07:14 sw1xvdtiv volumio[674]: code: 'ECONNREFUSED', Aug 25 23:07:14 sw1xvdtiv volumio[674]: syscall: 'connect', Aug 25 23:07:14 sw1xvdtiv volumio[674]: address: '158.69.210.98', Aug 25 23:07:14 sw1xvdtiv volumio[674]: port: 443 Aug 25 23:07:14 sw1xvdtiv volumio[674]: } Aug 25 23:07:14 sw1xvdtiv volumio[674]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 23:07:15 sw1xvdtiv sudo[2224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-25 23:06 Aug 25 23:07:15 sw1xvdtiv sudo[2224]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"