Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::volumioNext Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::next Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::stop Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::stPlaybackTimer Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::updateTrackBlock Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrackBlock Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 1 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::serviceStop Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 1 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::serviceStop Mar 28 13:44:01 volumio4 volumio[1539]: info: ControllerMpd::stop Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.365Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.366Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/02 - Find My Baby.flac" title="2 - Find My Baby" Mar 28 13:44:01 volumio4 volumio[1539]: info: Mar 28 13:44:01 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand stop took 11 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::play index undefined Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::startPlaybackTimer Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::clearAddPlayTracks NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::updateTrackBlock Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrackBlock Mar 28 13:44:01 volumio4 volumio[1539]: info: Mar 28 13:44:01 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:01 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:01 volumio4 volumio[1539]: info: Mar 28 13:44:01 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:01 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand status took 2 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand stop took 1 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand status took 1 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand status took 1 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:01 volumio4 volumio[1539]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 28 13:44:01 volumio4 volumio[1539]: info: Mar 28 13:44:01 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:01 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand clear took 3 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand add "NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac" Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:01 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Find My Baby","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/02 - Find My Baby.flac","trackType":"flac"} Mar 28 13:44:01 volumio4 volumio[1539]: verbose: CURRENT POSITION 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService stop Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus stop Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:01 volumio4 volumio[1539]: info: No code Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:01 volumio4 volumio[1539]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 28 13:44:01 volumio4 volumio[1539]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.400Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.400Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac" title="3 - Porcelain" Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.400Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.400Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac" title="3 - Porcelain" Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.401Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.401Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac" title="3 - Porcelain" Mar 28 13:44:01 volumio4 volumio[1539]: info: ------------------------------ 28ms Mar 28 13:44:01 volumio4 volumio[1539]: info: ------------------------------ 26ms Mar 28 13:44:01 volumio4 volumio[1539]: info: ------------------------------ 26ms Mar 28 13:44:01 volumio4 volumio[1539]: info: Mar 28 13:44:01 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:01 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:01 volumio4 volumio[1539]: info: Mar 28 13:44:01 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:01 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:01 volumio4 volumio[1539]: error: updateQueue error: null Mar 28 13:44:01 volumio4 volumio[1539]: info: Mar 28 13:44:01 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:01 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:01 volumio4 volumio[1539]: info: ------------------------------ 21ms Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand add "NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac" took 21 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: info: ------------------------------ 2ms Mar 28 13:44:01 volumio4 volumio[1539]: info: ------------------------------ 1ms Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand play Mar 28 13:44:01 volumio4 volumio[1539]: info: Mar 28 13:44:01 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:01 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:01 volumio4 volumio[1539]: info: Mar 28 13:44:01 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:01 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:01 volumio4 volumio[1539]: info: ------------------------------ 10ms Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand play took 9 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: info: ------------------------------ 9ms Mar 28 13:44:01 volumio4 volumio[1539]: info: ------------------------------ 9ms Mar 28 13:44:01 volumio4 volumio[1539]: info: Mar 28 13:44:01 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:01 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:01 volumio4 volumio[1539]: info: Mar 28 13:44:01 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:01 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:01 volumio4 volumio[1539]: info: Mar 28 13:44:01 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:01 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand status took 2 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:01 volumio4 volumio[1539]: info: Mar 28 13:44:01 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:01 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand status took 3 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand status took 2 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:01 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":241,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"684 Kbps","isStreaming":false,"title":"Porcelain","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac","trackType":"flac"} Mar 28 13:44:01 volumio4 volumio[1539]: verbose: CURRENT POSITION 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus stop Mar 28 13:44:01 volumio4 volumio[1539]: info: ------------------------------ 5ms Mar 28 13:44:01 volumio4 volumio[1539]: info: Mar 28 13:44:01 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:01 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:01 volumio4 volumio[1539]: info: Mar 28 13:44:01 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:01 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand status took 2 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand status took 1 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand status took 0 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:01 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":241,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"710 Kbps","isStreaming":false,"title":"Porcelain","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac","trackType":"flac"} Mar 28 13:44:01 volumio4 volumio[1539]: verbose: CURRENT POSITION 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:01 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:01 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":241,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"710 Kbps","isStreaming":false,"title":"Porcelain","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac","trackType":"flac"} Mar 28 13:44:01 volumio4 volumio[1539]: verbose: CURRENT POSITION 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:01 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.517Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=848 volume=100 Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.517Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=848 volume=100 Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.517Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac" title="3 - Porcelain" Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.517Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac" title="3 - Porcelain" Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.517Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=848 volume=100 Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.517Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac" title="3 - Porcelain" Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.519Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=848 volume=100 Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.520Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac" title="3 - Porcelain" Mar 28 13:44:01 volumio4 volumio[1539]: info: ------------------------------ 24ms Mar 28 13:44:01 volumio4 volumio[1539]: info: ------------------------------ 24ms Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 21 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 21 milliseconds Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:01 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:01 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":241,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"710 Kbps","isStreaming":false,"title":"Porcelain","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac","trackType":"flac"} Mar 28 13:44:01 volumio4 volumio[1539]: verbose: CURRENT POSITION 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:01 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:01 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":241,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"710 Kbps","isStreaming":false,"title":"Porcelain","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac","trackType":"flac"} Mar 28 13:44:01 volumio4 volumio[1539]: verbose: CURRENT POSITION 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:01 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:01 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":241,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"710 Kbps","isStreaming":false,"title":"Porcelain","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac","trackType":"flac"} Mar 28 13:44:01 volumio4 volumio[1539]: verbose: CURRENT POSITION 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:01 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:01 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:01 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.547Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=848 volume=100 Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.548Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=848 volume=100 Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.551Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac" title="3 - Porcelain" Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.548Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=848 volume=100 Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.551Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac" title="3 - Porcelain" Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.548Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=848 volume=100 Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.551Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac" title="3 - Porcelain" Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.549Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=848 volume=100 Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.551Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac" title="3 - Porcelain" Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.551Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac" title="3 - Porcelain" Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.551Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=848 volume=100 Mar 28 13:44:01 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:01.551Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac" title="3 - Porcelain" Mar 28 13:44:01 volumio4 volumio[1539]: info: ------------------------------ 53ms Mar 28 13:44:01 volumio4 volumio[1539]: info: ------------------------------ 52ms Mar 28 13:44:01 volumio4 volumio[1539]: info: ------------------------------ 53ms Mar 28 13:44:01 volumio4 volumio[1539]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioNext Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::next Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::stop Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::stPlaybackTimer Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::updateTrackBlock Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrackBlock Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::serviceStop Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 2 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::serviceStop Mar 28 13:44:02 volumio4 volumio[1539]: info: ControllerMpd::stop Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.646Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.647Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac" title="3 - Porcelain" Mar 28 13:44:02 volumio4 volumio[1539]: info: Mar 28 13:44:02 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand stop took 12 milliseconds Mar 28 13:44:02 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::play index undefined Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::startPlaybackTimer Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::clearAddPlayTracks NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::updateTrackBlock Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrackBlock Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand status took 1 milliseconds Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand stop took 0 milliseconds Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 13:44:02 volumio4 volumio[1539]: info: Mar 28 13:44:02 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:02 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:02 volumio4 volumio[1539]: info: Mar 28 13:44:02 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:02 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:02 volumio4 volumio[1539]: info: Mar 28 13:44:02 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:02 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand clear took 2 milliseconds Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand status took 1 milliseconds Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand add "NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:02 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Porcelain","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/03 - Porcelain.flac","trackType":"flac"} Mar 28 13:44:02 volumio4 volumio[1539]: verbose: CURRENT POSITION 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService stop Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus stop Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:02 volumio4 volumio[1539]: info: No code Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:02 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 13:44:02 volumio4 volumio[1539]: verbose: CURRENT POSITION 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService stop Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus stop Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:02 volumio4 volumio[1539]: info: No code Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.678Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.678Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" title="4 - Why Does My Heart Feel So Bad?" Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.678Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.678Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" title="4 - Why Does My Heart Feel So Bad?" Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.679Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.679Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" title="4 - Why Does My Heart Feel So Bad?" Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.679Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.679Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" title="4 - Why Does My Heart Feel So Bad?" Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.680Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.680Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" title="4 - Why Does My Heart Feel So Bad?" Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.680Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.680Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" title="4 - Why Does My Heart Feel So Bad?" Mar 28 13:44:02 volumio4 volumio[1539]: info: ------------------------------ 26ms Mar 28 13:44:02 volumio4 volumio[1539]: info: ------------------------------ 26ms Mar 28 13:44:02 volumio4 volumio[1539]: info: Mar 28 13:44:02 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:02 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:02 volumio4 volumio[1539]: info: Mar 28 13:44:02 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:02 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:02 volumio4 volumio[1539]: error: updateQueue error: null Mar 28 13:44:02 volumio4 volumio[1539]: info: Mar 28 13:44:02 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:02 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand status took 26 milliseconds Mar 28 13:44:02 volumio4 volumio[1539]: info: ------------------------------ 25ms Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand add "NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" took 25 milliseconds Mar 28 13:44:02 volumio4 volumio[1539]: info: ------------------------------ 1ms Mar 28 13:44:02 volumio4 volumio[1539]: info: ------------------------------ 1ms Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand play Mar 28 13:44:02 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 13:44:02 volumio4 volumio[1539]: verbose: CURRENT POSITION 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService stop Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus stop Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:02 volumio4 volumio[1539]: info: No code Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.695Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=26 volume=100 Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.696Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" title="4 - Why Does My Heart Feel So Bad?" Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.696Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=26 volume=100 Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.696Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" title="4 - Why Does My Heart Feel So Bad?" Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.696Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=26 volume=100 Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.697Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" title="4 - Why Does My Heart Feel So Bad?" Mar 28 13:44:02 volumio4 volumio[1539]: info: ------------------------------ 39ms Mar 28 13:44:02 volumio4 volumio[1539]: info: Mar 28 13:44:02 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:02 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:02 volumio4 volumio[1539]: info: Mar 28 13:44:02 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:02 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:02 volumio4 volumio[1539]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 28 13:44:02 volumio4 volumio[1539]: info: ------------------------------ 25ms Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand play took 25 milliseconds Mar 28 13:44:02 volumio4 volumio[1539]: info: ------------------------------ 11ms Mar 28 13:44:02 volumio4 volumio[1539]: info: ------------------------------ 11ms Mar 28 13:44:02 volumio4 volumio[1539]: info: Mar 28 13:44:02 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:02 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:02 volumio4 volumio[1539]: info: Mar 28 13:44:02 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:02 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:02 volumio4 volumio[1539]: info: Mar 28 13:44:02 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:02 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand status took 3 milliseconds Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:02 volumio4 volumio[1539]: info: Mar 28 13:44:02 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:02 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:02 volumio4 volumio[1539]: info: Mar 28 13:44:02 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:02 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand status took 3 milliseconds Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand status took 3 milliseconds Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:02 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Why Does My Heart Feel So Bad?","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac","trackType":"flac"} Mar 28 13:44:02 volumio4 volumio[1539]: verbose: CURRENT POSITION 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus stop Mar 28 13:44:02 volumio4 volumio[1539]: info: ------------------------------ 5ms Mar 28 13:44:02 volumio4 volumio[1539]: info: Mar 28 13:44:02 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:02 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand status took 1 milliseconds Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand status took 1 milliseconds Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand status took 0 milliseconds Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:02 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Why Does My Heart Feel So Bad?","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac","trackType":"flac"} Mar 28 13:44:02 volumio4 volumio[1539]: verbose: CURRENT POSITION 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:02 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:02 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Why Does My Heart Feel So Bad?","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac","trackType":"flac"} Mar 28 13:44:02 volumio4 volumio[1539]: verbose: CURRENT POSITION 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:02 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.775Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.775Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" title="4 - Why Does My Heart Feel So Bad?" Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.777Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.777Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" title="4 - Why Does My Heart Feel So Bad?" Mar 28 13:44:02 volumio4 volumio[1539]: info: ------------------------------ 18ms Mar 28 13:44:02 volumio4 volumio[1539]: info: ------------------------------ 18ms Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.777Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.778Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.778Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" title="4 - Why Does My Heart Feel So Bad?" Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.778Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" title="4 - Why Does My Heart Feel So Bad?" Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 28 13:44:02 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:02 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:02 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Why Does My Heart Feel So Bad?","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac","trackType":"flac"} Mar 28 13:44:02 volumio4 volumio[1539]: verbose: CURRENT POSITION 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:02 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:02 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Why Does My Heart Feel So Bad?","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac","trackType":"flac"} Mar 28 13:44:02 volumio4 volumio[1539]: verbose: CURRENT POSITION 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:02 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:02 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Why Does My Heart Feel So Bad?","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac","trackType":"flac"} Mar 28 13:44:02 volumio4 volumio[1539]: verbose: CURRENT POSITION 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:02 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:02 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:02 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.798Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.798Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.798Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" title="4 - Why Does My Heart Feel So Bad?" Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.798Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.798Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" title="4 - Why Does My Heart Feel So Bad?" Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.798Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.798Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.798Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" title="4 - Why Does My Heart Feel So Bad?" Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.798Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" title="4 - Why Does My Heart Feel So Bad?" Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.798Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.798Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" title="4 - Why Does My Heart Feel So Bad?" Mar 28 13:44:02 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:02.798Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" title="4 - Why Does My Heart Feel So Bad?" Mar 28 13:44:02 volumio4 volumio[1539]: info: ------------------------------ 37ms Mar 28 13:44:02 volumio4 volumio[1539]: info: ------------------------------ 37ms Mar 28 13:44:02 volumio4 volumio[1539]: info: ------------------------------ 36ms Mar 28 13:44:02 volumio4 volumio[1539]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 28 13:44:03 volumio4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 19. Mar 28 13:44:03 volumio4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 28 13:44:03 volumio4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 28 13:44:03 volumio4 upmpdcli[3033]: Could not open config: /tmp/upmpdcli.conf Mar 28 13:44:03 volumio4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 28 13:44:03 volumio4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreCommandRouter::volumioNext Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreStateMachine::next Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreStateMachine::stop Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreStateMachine::stPlaybackTimer Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreStateMachine::updateTrackBlock Mar 28 13:44:03 volumio4 volumio[1539]: info: CorePlayQueue::getTrackBlock Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:03 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreStateMachine::serviceStop Mar 28 13:44:03 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 3 Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreCommandRouter::serviceStop Mar 28 13:44:03 volumio4 volumio[1539]: info: ControllerMpd::stop Mar 28 13:44:03 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 13:44:03 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:03.944Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:03 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:03.945Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac" title="4 - Why Does My Heart Feel So Bad?" Mar 28 13:44:03 volumio4 volumio[1539]: info: Mar 28 13:44:03 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:03 volumio4 volumio[1539]: info: sendMpdCommand stop took 14 milliseconds Mar 28 13:44:03 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:03 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreStateMachine::play index undefined Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:44:03 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreStateMachine::startPlaybackTimer Mar 28 13:44:03 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:03 volumio4 volumio[1539]: verbose: ControllerMpd::clearAddPlayTracks NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac Mar 28 13:44:03 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreStateMachine::updateTrackBlock Mar 28 13:44:03 volumio4 volumio[1539]: info: CorePlayQueue::getTrackBlock Mar 28 13:44:03 volumio4 volumio[1539]: info: Mar 28 13:44:03 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:03 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:03 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:03 volumio4 volumio[1539]: info: Mar 28 13:44:03 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:03 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:03 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:03 volumio4 volumio[1539]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 28 13:44:03 volumio4 volumio[1539]: info: sendMpdCommand status took 3 milliseconds Mar 28 13:44:03 volumio4 volumio[1539]: info: sendMpdCommand stop took 3 milliseconds Mar 28 13:44:03 volumio4 volumio[1539]: info: sendMpdCommand status took 2 milliseconds Mar 28 13:44:03 volumio4 volumio[1539]: info: sendMpdCommand status took 3 milliseconds Mar 28 13:44:03 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:03 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:03 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 13:44:03 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:03 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:03 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:03 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:03 volumio4 volumio[1539]: info: Mar 28 13:44:03 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:03 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:03 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 28 13:44:03 volumio4 volumio[1539]: info: sendMpdCommand clear took 1 milliseconds Mar 28 13:44:03 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 13:44:03 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 13:44:03 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:03 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand add "NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" Mar 28 13:44:03 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:03 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:03 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:03 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:03 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:03 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Why Does My Heart Feel So Bad?","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/04 - Why Does My Heart Feel So Bad.flac","trackType":"flac"} Mar 28 13:44:03 volumio4 volumio[1539]: verbose: CURRENT POSITION 4 Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService stop Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus stop Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:03 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:03 volumio4 volumio[1539]: info: No code Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:03 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:03 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:03 volumio4 volumio[1539]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 28 13:44:03 volumio4 volumio[1539]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 28 13:44:03 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:03.975Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:03 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:03.975Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:03 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:03.975Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:03 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:03.975Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:03 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:03.975Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:03 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:03.975Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:03 volumio4 volumio[1539]: info: ------------------------------ 22ms Mar 28 13:44:03 volumio4 volumio[1539]: info: ------------------------------ 19ms Mar 28 13:44:03 volumio4 volumio[1539]: info: ------------------------------ 19ms Mar 28 13:44:03 volumio4 volumio[1539]: info: Mar 28 13:44:03 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:03 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:03 volumio4 volumio[1539]: info: Mar 28 13:44:03 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:03 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:03 volumio4 volumio[1539]: error: updateQueue error: null Mar 28 13:44:03 volumio4 volumio[1539]: info: Mar 28 13:44:03 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:03 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:03 volumio4 volumio[1539]: info: ------------------------------ 17ms Mar 28 13:44:03 volumio4 volumio[1539]: info: sendMpdCommand add "NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" took 16 milliseconds Mar 28 13:44:03 volumio4 volumio[1539]: info: ------------------------------ 0ms Mar 28 13:44:03 volumio4 volumio[1539]: info: ------------------------------ 0ms Mar 28 13:44:03 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand play Mar 28 13:44:03 volumio4 volumio[1539]: info: Mar 28 13:44:03 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:03 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:03 volumio4 volumio[1539]: info: Mar 28 13:44:03 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:03 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:03 volumio4 volumio[1539]: info: ------------------------------ 8ms Mar 28 13:44:03 volumio4 volumio[1539]: info: sendMpdCommand play took 8 milliseconds Mar 28 13:44:03 volumio4 volumio[1539]: info: ------------------------------ 7ms Mar 28 13:44:03 volumio4 volumio[1539]: info: ------------------------------ 7ms Mar 28 13:44:04 volumio4 volumio[1539]: info: Mar 28 13:44:04 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:04 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:04 volumio4 volumio[1539]: info: Mar 28 13:44:04 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:04 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:04 volumio4 volumio[1539]: info: Mar 28 13:44:04 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:04 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:04 volumio4 volumio[1539]: info: Mar 28 13:44:04 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:04 volumio4 volumio[1539]: info: sendMpdCommand status took 4 milliseconds Mar 28 13:44:04 volumio4 volumio[1539]: info: sendMpdCommand status took 4 milliseconds Mar 28 13:44:04 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:04 volumio4 volumio[1539]: info: sendMpdCommand status took 4 milliseconds Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:04 volumio4 volumio[1539]: info: Mar 28 13:44:04 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:04 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:04 volumio4 volumio[1539]: info: Mar 28 13:44:04 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:04 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:04 volumio4 volumio[1539]: info: sendMpdCommand status took 1 milliseconds Mar 28 13:44:04 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 13:44:04 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 13:44:04 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 13:44:04 volumio4 volumio[1539]: info: sendMpdCommand status took 0 milliseconds Mar 28 13:44:04 volumio4 volumio[1539]: info: sendMpdCommand status took 0 milliseconds Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:04 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:04 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"South Side","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac","trackType":"flac"} Mar 28 13:44:04 volumio4 volumio[1539]: verbose: CURRENT POSITION 4 Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus stop Mar 28 13:44:04 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:04 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"South Side","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac","trackType":"flac"} Mar 28 13:44:04 volumio4 volumio[1539]: verbose: CURRENT POSITION 4 Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:04 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:04 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:04 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"South Side","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac","trackType":"flac"} Mar 28 13:44:04 volumio4 volumio[1539]: verbose: CURRENT POSITION 4 Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:04 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:04 volumio4 volumio[1539]: info: ------------------------------ 11ms Mar 28 13:44:04 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:04.105Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:04 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:04.105Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:04 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:04.105Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:04 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:04.106Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:04 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:04.106Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:04 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:04.106Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:04 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:04.106Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:04 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:04.106Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:04 volumio4 volumio[1539]: info: ------------------------------ 23ms Mar 28 13:44:04 volumio4 volumio[1539]: info: ------------------------------ 23ms Mar 28 13:44:04 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 28 13:44:04 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 28 13:44:04 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:04 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:04 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:04 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"South Side","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac","trackType":"flac"} Mar 28 13:44:04 volumio4 volumio[1539]: verbose: CURRENT POSITION 4 Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:04 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:04 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:04 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"South Side","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac","trackType":"flac"} Mar 28 13:44:04 volumio4 volumio[1539]: verbose: CURRENT POSITION 4 Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:04 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:04 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:04 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"South Side","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac","trackType":"flac"} Mar 28 13:44:04 volumio4 volumio[1539]: verbose: CURRENT POSITION 4 Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:04 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:04 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:04 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:04 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:04.138Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:04 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:04.138Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:04 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:04.138Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:04 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:04.139Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:04 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:04.139Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:04 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:04.139Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:04 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:04.141Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:04 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:04.141Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:04 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:04.142Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:04 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:04.142Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:04 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:04.143Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:04 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:04.143Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:04 volumio4 volumio[1539]: info: ------------------------------ 55ms Mar 28 13:44:04 volumio4 volumio[1539]: info: ------------------------------ 56ms Mar 28 13:44:04 volumio4 volumio[1539]: info: ------------------------------ 55ms Mar 28 13:44:04 volumio4 volumio[1539]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreCommandRouter::volumioSeek Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreStateMachine::seek Mar 28 13:44:07 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:07 volumio4 volumio[1539]: info: TRACKBLOCK {"uri":"mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac","service":"mpd","name":"5 - South Side","artist":"Moby","album":"Play","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=391&web=Moby/Play/extralarge&path=%2FNAS%2FAsustor_FLAC%2FMoby%20-%20Play%20(1999)%20%5BFLAC%5D&metadata=false","duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreStateMachine::startPlaybackTimer Mar 28 13:44:07 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:07 volumio4 volumio[1539]: info: ControllerMpd::seek Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:07 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:07 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:07.223Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=55000 volume=100 Mar 28 13:44:07 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:07.223Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:07 volumio4 volumio[1539]: error: null Mar 28 13:44:07 volumio4 volumio[1539]: info: Mar 28 13:44:07 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:07 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:07 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:07 volumio4 volumio[1539]: info: Mar 28 13:44:07 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:07 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:07 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:07 volumio4 volumio[1539]: info: Mar 28 13:44:07 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:07 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:07 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:07 volumio4 volumio[1539]: info: sendMpdCommand status took 2 milliseconds Mar 28 13:44:07 volumio4 volumio[1539]: info: sendMpdCommand status took 2 milliseconds Mar 28 13:44:07 volumio4 volumio[1539]: info: sendMpdCommand status took 1 milliseconds Mar 28 13:44:07 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:07 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:07 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:07 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:07 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:07 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:07 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 13:44:07 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 13:44:07 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 13:44:07 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:07 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:07 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:07 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:07 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:07 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":55848,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1010 Kbps","isStreaming":false,"title":"South Side","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac","trackType":"flac"} Mar 28 13:44:07 volumio4 volumio[1539]: verbose: CURRENT POSITION 4 Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:07 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:07 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:07 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:07 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:07 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:07 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":55848,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1010 Kbps","isStreaming":false,"title":"South Side","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac","trackType":"flac"} Mar 28 13:44:07 volumio4 volumio[1539]: verbose: CURRENT POSITION 4 Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:07 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:07 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:07 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:07 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:07 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:07 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":55848,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1010 Kbps","isStreaming":false,"title":"South Side","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac","trackType":"flac"} Mar 28 13:44:07 volumio4 volumio[1539]: verbose: CURRENT POSITION 4 Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:07 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:07 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:07 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:07 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:07 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:07.248Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=55848 volume=100 Mar 28 13:44:07 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:07.248Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:07 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:07.250Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=55848 volume=100 Mar 28 13:44:07 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:07.250Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:07 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:07.251Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=55848 volume=100 Mar 28 13:44:07 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:07.252Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:07 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:07.254Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=55848 volume=100 Mar 28 13:44:07 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:07.254Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:07 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:07.255Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=55848 volume=100 Mar 28 13:44:07 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:07.255Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:07 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:07.256Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=55848 volume=100 Mar 28 13:44:07 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:07.256Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:07 volumio4 volumio[1539]: info: ------------------------------ 34ms Mar 28 13:44:07 volumio4 volumio[1539]: info: ------------------------------ 34ms Mar 28 13:44:07 volumio4 volumio[1539]: info: ------------------------------ 34ms Mar 28 13:44:18 volumio4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 20. Mar 28 13:44:18 volumio4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 28 13:44:18 volumio4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 28 13:44:18 volumio4 upmpdcli[3056]: Could not open config: /tmp/upmpdcli.conf Mar 28 13:44:18 volumio4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 28 13:44:18 volumio4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 28 13:44:33 volumio4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 21. Mar 28 13:44:33 volumio4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 28 13:44:33 volumio4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 28 13:44:33 volumio4 upmpdcli[3086]: Could not open config: /tmp/upmpdcli.conf Mar 28 13:44:33 volumio4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 28 13:44:33 volumio4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::volumioNext Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::next Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::stop Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::stPlaybackTimer Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::updateTrackBlock Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrackBlock Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::serviceStop Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 4 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::serviceStop Mar 28 13:44:33 volumio4 volumio[1539]: info: ControllerMpd::stop Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.651Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.652Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac" title="5 - South Side" Mar 28 13:44:33 volumio4 volumio[1539]: info: Mar 28 13:44:33 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand stop took 11 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::play index undefined Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::startPlaybackTimer Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::clearAddPlayTracks NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::updateTrackBlock Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrackBlock Mar 28 13:44:33 volumio4 volumio[1539]: info: Mar 28 13:44:33 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:33 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:33 volumio4 volumio[1539]: info: Mar 28 13:44:33 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:33 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand status took 2 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand stop took 2 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand status took 2 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand status took 1 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:33 volumio4 volumio[1539]: info: Mar 28 13:44:33 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:33 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand clear took 0 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand add "NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac" Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:33 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"South Side","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/05 - South Side.flac","trackType":"flac"} Mar 28 13:44:33 volumio4 volumio[1539]: verbose: CURRENT POSITION 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService stop Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus stop Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:33 volumio4 volumio[1539]: info: No code Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:33 volumio4 volumio[1539]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 28 13:44:33 volumio4 volumio[1539]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.681Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.681Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac" title="6 - Rushing" Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.681Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.682Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac" title="6 - Rushing" Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.683Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.683Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac" title="6 - Rushing" Mar 28 13:44:33 volumio4 volumio[1539]: info: ------------------------------ 24ms Mar 28 13:44:33 volumio4 volumio[1539]: info: ------------------------------ 23ms Mar 28 13:44:33 volumio4 volumio[1539]: info: ------------------------------ 22ms Mar 28 13:44:33 volumio4 volumio[1539]: info: Mar 28 13:44:33 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:33 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:33 volumio4 volumio[1539]: info: Mar 28 13:44:33 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:33 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:33 volumio4 volumio[1539]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 28 13:44:33 volumio4 volumio[1539]: error: updateQueue error: null Mar 28 13:44:33 volumio4 volumio[1539]: info: Mar 28 13:44:33 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:33 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:33 volumio4 volumio[1539]: info: ------------------------------ 21ms Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand add "NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac" took 20 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: info: ------------------------------ 1ms Mar 28 13:44:33 volumio4 volumio[1539]: info: ------------------------------ 1ms Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand play Mar 28 13:44:33 volumio4 volumio[1539]: info: Mar 28 13:44:33 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:33 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:33 volumio4 volumio[1539]: info: Mar 28 13:44:33 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:33 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:33 volumio4 volumio[1539]: info: ------------------------------ 10ms Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand play took 8 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: info: ------------------------------ 8ms Mar 28 13:44:33 volumio4 volumio[1539]: info: ------------------------------ 7ms Mar 28 13:44:33 volumio4 volumio[1539]: info: Mar 28 13:44:33 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:33 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:33 volumio4 volumio[1539]: info: Mar 28 13:44:33 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:33 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:33 volumio4 volumio[1539]: info: Mar 28 13:44:33 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:33 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:33 volumio4 volumio[1539]: info: Mar 28 13:44:33 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand status took 3 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:33 volumio4 volumio[1539]: info: Mar 28 13:44:33 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:33 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand status took 4 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand status took 5 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:33 volumio4 volumio[1539]: info: Mar 28 13:44:33 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:33 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand status took 3 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand status took 2 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:33 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":180,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rushing","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac","trackType":"flac"} Mar 28 13:44:33 volumio4 volumio[1539]: verbose: CURRENT POSITION 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus stop Mar 28 13:44:33 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":180,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rushing","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac","trackType":"flac"} Mar 28 13:44:33 volumio4 volumio[1539]: verbose: CURRENT POSITION 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:33 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:33 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":180,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rushing","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac","trackType":"flac"} Mar 28 13:44:33 volumio4 volumio[1539]: verbose: CURRENT POSITION 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:33 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:33 volumio4 volumio[1539]: info: ------------------------------ 13ms Mar 28 13:44:33 volumio4 volumio[1539]: info: ------------------------------ 25ms Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.818Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.818Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.818Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.818Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac" title="6 - Rushing" Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.818Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.818Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac" title="6 - Rushing" Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.818Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac" title="6 - Rushing" Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.818Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac" title="6 - Rushing" Mar 28 13:44:33 volumio4 volumio[1539]: info: ------------------------------ 30ms Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand status took 26 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 25 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 25 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:33 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":180,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rushing","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac","trackType":"flac"} Mar 28 13:44:33 volumio4 volumio[1539]: verbose: CURRENT POSITION 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:33 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:33 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":180,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"834 Kbps","isStreaming":false,"title":"Rushing","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac","trackType":"flac"} Mar 28 13:44:33 volumio4 volumio[1539]: verbose: CURRENT POSITION 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:33 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.841Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.842Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac" title="6 - Rushing" Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.842Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=848 volume=100 Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.842Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=848 volume=100 Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.842Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac" title="6 - Rushing" Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.842Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac" title="6 - Rushing" Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.842Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.842Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac" title="6 - Rushing" Mar 28 13:44:33 volumio4 volumio[1539]: info: ------------------------------ 48ms Mar 28 13:44:33 volumio4 volumio[1539]: info: ------------------------------ 48ms Mar 28 13:44:33 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 34 milliseconds Mar 28 13:44:33 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:33 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":180,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"834 Kbps","isStreaming":false,"title":"Rushing","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac","trackType":"flac"} Mar 28 13:44:33 volumio4 volumio[1539]: verbose: CURRENT POSITION 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:33 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:33 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:33 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.864Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=848 volume=100 Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.864Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac" title="6 - Rushing" Mar 28 13:44:33 volumio4 volumio[1539]: info: ------------------------------ 67ms Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.865Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=848 volume=100 Mar 28 13:44:33 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:33.866Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac" title="6 - Rushing" Mar 28 13:44:33 volumio4 volumio[1539]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::volumioNext Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::next Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::stop Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::stPlaybackTimer Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::updateTrackBlock Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrackBlock Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::serviceStop Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 5 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::serviceStop Mar 28 13:44:35 volumio4 volumio[1539]: info: ControllerMpd::stop Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.123Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.123Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac" title="6 - Rushing" Mar 28 13:44:35 volumio4 volumio[1539]: info: Mar 28 13:44:35 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand stop took 20 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::play index undefined Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::startPlaybackTimer Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::clearAddPlayTracks NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::updateTrackBlock Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrackBlock Mar 28 13:44:35 volumio4 volumio[1539]: info: Mar 28 13:44:35 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:35 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:35 volumio4 volumio[1539]: info: Mar 28 13:44:35 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:35 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand status took 3 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand stop took 3 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand status took 0 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand status took 1 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:35 volumio4 volumio[1539]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 28 13:44:35 volumio4 volumio[1539]: info: Mar 28 13:44:35 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:35 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand clear took 1 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand add "NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:35 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rushing","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/06 - Rushing.flac","trackType":"flac"} Mar 28 13:44:35 volumio4 volumio[1539]: verbose: CURRENT POSITION 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService stop Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus stop Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:35 volumio4 volumio[1539]: info: No code Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:35 volumio4 volumio[1539]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 28 13:44:35 volumio4 volumio[1539]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.156Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.156Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.156Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.157Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.157Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.157Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:44:35 volumio4 volumio[1539]: info: ------------------------------ 20ms Mar 28 13:44:35 volumio4 volumio[1539]: info: ------------------------------ 19ms Mar 28 13:44:35 volumio4 volumio[1539]: info: ------------------------------ 16ms Mar 28 13:44:35 volumio4 volumio[1539]: info: Mar 28 13:44:35 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:35 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:35 volumio4 volumio[1539]: info: Mar 28 13:44:35 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:35 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:35 volumio4 volumio[1539]: error: updateQueue error: null Mar 28 13:44:35 volumio4 volumio[1539]: info: Mar 28 13:44:35 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:35 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:35 volumio4 volumio[1539]: info: ------------------------------ 14ms Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand add "NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" took 14 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: info: ------------------------------ 0ms Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand play Mar 28 13:44:35 volumio4 volumio[1539]: info: Mar 28 13:44:35 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:35 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:35 volumio4 volumio[1539]: info: Mar 28 13:44:35 volumio4 volumio[1539]: ---------------------------- MPD announces system playlist update Mar 28 13:44:35 volumio4 volumio[1539]: info: Ignoring MPD Status Update Mar 28 13:44:35 volumio4 volumio[1539]: info: ------------------------------ 9ms Mar 28 13:44:35 volumio4 volumio[1539]: info: ------------------------------ 9ms Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand play took 8 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: info: ------------------------------ 8ms Mar 28 13:44:35 volumio4 volumio[1539]: info: ------------------------------ 8ms Mar 28 13:44:35 volumio4 volumio[1539]: info: Mar 28 13:44:35 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:35 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:35 volumio4 volumio[1539]: info: Mar 28 13:44:35 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:35 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:35 volumio4 volumio[1539]: info: Mar 28 13:44:35 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:35 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand status took 3 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:35 volumio4 volumio[1539]: info: Mar 28 13:44:35 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:35 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:35 volumio4 volumio[1539]: info: Mar 28 13:44:35 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:35 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand status took 4 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand status took 4 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:35 volumio4 volumio[1539]: info: Mar 28 13:44:35 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:35 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand status took 1 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand status took 0 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:35 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bodyrock","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac","trackType":"flac"} Mar 28 13:44:35 volumio4 volumio[1539]: verbose: CURRENT POSITION 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus stop Mar 28 13:44:35 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bodyrock","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac","trackType":"flac"} Mar 28 13:44:35 volumio4 volumio[1539]: verbose: CURRENT POSITION 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:35 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:35 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bodyrock","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac","trackType":"flac"} Mar 28 13:44:35 volumio4 volumio[1539]: verbose: CURRENT POSITION 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:35 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:35 volumio4 volumio[1539]: info: ------------------------------ 9ms Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.247Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.247Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.247Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.247Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.248Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.248Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.248Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.249Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:44:35 volumio4 volumio[1539]: info: ------------------------------ 19ms Mar 28 13:44:35 volumio4 volumio[1539]: info: ------------------------------ 19ms Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand status took 16 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:35 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bodyrock","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac","trackType":"flac"} Mar 28 13:44:35 volumio4 volumio[1539]: verbose: CURRENT POSITION 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:35 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:35 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bodyrock","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac","trackType":"flac"} Mar 28 13:44:35 volumio4 volumio[1539]: verbose: CURRENT POSITION 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:35 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.277Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.277Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:44:35 volumio4 volumio[1539]: info: ------------------------------ 48ms Mar 28 13:44:35 volumio4 volumio[1539]: info: ------------------------------ 48ms Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.283Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.283Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.283Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.284Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.284Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.284Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:44:35 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 52 milliseconds Mar 28 13:44:35 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:35 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bodyrock","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac","trackType":"flac"} Mar 28 13:44:35 volumio4 volumio[1539]: verbose: CURRENT POSITION 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:35 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:35 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:35 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.311Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.311Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.311Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:44:35 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:35.312Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:44:35 volumio4 volumio[1539]: info: ------------------------------ 78ms Mar 28 13:44:35 volumio4 volumio[1539]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreCommandRouter::volumioSeek Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreStateMachine::seek Mar 28 13:44:37 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:37 volumio4 volumio[1539]: info: TRACKBLOCK {"uri":"mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac","service":"mpd","name":"7 - Bodyrock","artist":"Moby","album":"Play","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=391&web=Moby/Play/extralarge&path=%2FNAS%2FAsustor_FLAC%2FMoby%20-%20Play%20(1999)%20%5BFLAC%5D&metadata=false","duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreStateMachine::startPlaybackTimer Mar 28 13:44:37 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:37 volumio4 volumio[1539]: info: ControllerMpd::seek Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:37 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:37 volumio4 volumio[1539]: error: null Mar 28 13:44:37 volumio4 volumio[1539]: info: Mar 28 13:44:37 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:37 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:37.342Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=53000 volume=100 Mar 28 13:44:37 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:37 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:37 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:37.342Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:44:37 volumio4 volumio[1539]: info: Mar 28 13:44:37 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:37 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:37 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:37 volumio4 volumio[1539]: info: Mar 28 13:44:37 volumio4 volumio[1539]: ---------------------------- MPD announces state update: player Mar 28 13:44:37 volumio4 volumio[1539]: info: ControllerMpd::getState Mar 28 13:44:37 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:44:37 volumio4 volumio[1539]: info: sendMpdCommand status took 2 milliseconds Mar 28 13:44:37 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:37 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:37 volumio4 volumio[1539]: info: sendMpdCommand status took 3 milliseconds Mar 28 13:44:37 volumio4 volumio[1539]: info: sendMpdCommand status took 3 milliseconds Mar 28 13:44:37 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 28 13:44:37 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:37 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:37 volumio4 volumio[1539]: verbose: ControllerMpd::parseState Mar 28 13:44:37 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:44:37 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:37 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:37 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:37 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53000,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"857 Kbps","isStreaming":false,"title":"Bodyrock","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac","trackType":"flac"} Mar 28 13:44:37 volumio4 volumio[1539]: verbose: CURRENT POSITION 6 Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:37 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:37 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:37 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:37 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:37.362Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=53000 volume=100 Mar 28 13:44:37 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:37.363Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:44:37 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:37.363Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=53000 volume=100 Mar 28 13:44:37 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:37.364Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:44:37 volumio4 volumio[1539]: info: ------------------------------ 23ms Mar 28 13:44:37 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 28 13:44:37 volumio4 volumio[1539]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 28 13:44:37 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:37 volumio4 volumio[1539]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:44:37 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:37 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:37 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53848,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1007 Kbps","isStreaming":false,"title":"Bodyrock","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac","trackType":"flac"} Mar 28 13:44:37 volumio4 volumio[1539]: verbose: CURRENT POSITION 6 Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:37 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:37 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:37 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:37 volumio4 volumio[1539]: info: ControllerMpd::pushState Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreCommandRouter::servicePushState Mar 28 13:44:37 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:37 volumio4 volumio[1539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53848,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1007 Kbps","isStreaming":false,"title":"Bodyrock","artist":"Moby","album":"Play","uri":"NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac","trackType":"flac"} Mar 28 13:44:37 volumio4 volumio[1539]: verbose: CURRENT POSITION 6 Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreStateMachine::syncState stateService play Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:44:37 volumio4 volumio[1539]: info: Received an update from plugin. extracting info from payload Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:37 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:44:37 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:44:37 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:44:37 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:37.382Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=53848 volume=100 Mar 28 13:44:37 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:37.383Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:44:37 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:37.383Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=53848 volume=100 Mar 28 13:44:37 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:37.383Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:44:37 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:37.383Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=53848 volume=100 Mar 28 13:44:37 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:37.384Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:44:37 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:37.385Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_PLAYING positionMs=53848 volume=100 Mar 28 13:44:37 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:44:37.385Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:44:37 volumio4 volumio[1539]: info: ------------------------------ 43ms Mar 28 13:44:37 volumio4 volumio[1539]: info: ------------------------------ 43ms Mar 28 13:44:48 volumio4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 22. Mar 28 13:44:48 volumio4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 28 13:44:48 volumio4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 28 13:44:48 volumio4 upmpdcli[3113]: Could not open config: /tmp/upmpdcli.conf Mar 28 13:44:48 volumio4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 28 13:44:48 volumio4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 28 13:45:04 volumio4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 23. Mar 28 13:45:04 volumio4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 28 13:45:04 volumio4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 28 13:45:04 volumio4 upmpdcli[3146]: Could not open config: /tmp/upmpdcli.conf Mar 28 13:45:04 volumio4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 28 13:45:04 volumio4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 28 13:45:08 volumio4 nmbd[1256]: [2026/03/28 13:45:08.820292, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 28 13:45:08 volumio4 nmbd[1256]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.211 for name WORKGROUP<1d>. Mar 28 13:45:08 volumio4 nmbd[1256]: This response was from IP 192.168.1.67, reporting an IP address of 192.168.1.67. Mar 28 13:45:11 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 28 13:45:11 volumio4 volumio[1539]: info: CURURI: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC Mar 28 13:45:12 volumio4 volumio[1539]: info: Preload queue cleared Mar 28 13:45:12 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/01 - Patricia Barber - Touch Of Trash.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/02 - Patricia Barber - Winter.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/03 - Patricia Barber - You & The Night & The Music.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/04 - Patricia Barber - Constantinople.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/05 - Patricia Barber - Light My Fire.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/06 - Patricia Barber - Silent Partner.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/07 - Patricia Barber - Company.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/08 - Patricia Barber - Let It Rain.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/09 - Patricia Barber - Shens A Lady (Paul Anka).flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/10 - Patricia Barber - Love, Put On Your Faces.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/11 - Patricia Barber - Postmodern Blues.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/12 - Patricia Barber - Let It Rain - Vamp.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/01 - Patricia Barber - Touch Of Trash.flac in service mpd Mar 28 13:45:12 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/FLAC/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%20(1998)%20-%20FLAC%2F01%20-%20Patricia%20Barber%20-%20Touch%20Of%20Trash.flac&metadata=false Mar 28 13:45:12 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/01 - Patricia Barber - Touch Of Trash.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/02 - Patricia Barber - Winter.flac in service mpd Mar 28 13:45:12 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/FLAC/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%20(1998)%20-%20FLAC%2F02%20-%20Patricia%20Barber%20-%20Winter.flac&metadata=false Mar 28 13:45:12 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/02 - Patricia Barber - Winter.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/03 - Patricia Barber - You & The Night & The Music.flac in service mpd Mar 28 13:45:12 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/FLAC/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%20(1998)%20-%20FLAC%2F03%20-%20Patricia%20Barber%20-%20You%20%26%20The%20Night%20%26%20The%20Music.flac&metadata=false Mar 28 13:45:12 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/03 - Patricia Barber - You & The Night & The Music.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/04 - Patricia Barber - Constantinople.flac in service mpd Mar 28 13:45:12 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/FLAC/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%20(1998)%20-%20FLAC%2F04%20-%20Patricia%20Barber%20-%20Constantinople.flac&metadata=false Mar 28 13:45:12 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/04 - Patricia Barber - Constantinople.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/05 - Patricia Barber - Light My Fire.flac in service mpd Mar 28 13:45:12 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/FLAC/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%20(1998)%20-%20FLAC%2F05%20-%20Patricia%20Barber%20-%20Light%20My%20Fire.flac&metadata=false Mar 28 13:45:12 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/05 - Patricia Barber - Light My Fire.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/06 - Patricia Barber - Silent Partner.flac in service mpd Mar 28 13:45:12 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/FLAC/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%20(1998)%20-%20FLAC%2F06%20-%20Patricia%20Barber%20-%20Silent%20Partner.flac&metadata=false Mar 28 13:45:12 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/06 - Patricia Barber - Silent Partner.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/07 - Patricia Barber - Company.flac in service mpd Mar 28 13:45:12 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/FLAC/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%20(1998)%20-%20FLAC%2F07%20-%20Patricia%20Barber%20-%20Company.flac&metadata=false Mar 28 13:45:12 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/07 - Patricia Barber - Company.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/08 - Patricia Barber - Let It Rain.flac in service mpd Mar 28 13:45:12 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/FLAC/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%20(1998)%20-%20FLAC%2F08%20-%20Patricia%20Barber%20-%20Let%20It%20Rain.flac&metadata=false Mar 28 13:45:12 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/08 - Patricia Barber - Let It Rain.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/09 - Patricia Barber - Shens A Lady (Paul Anka).flac in service mpd Mar 28 13:45:12 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/FLAC/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%20(1998)%20-%20FLAC%2F09%20-%20Patricia%20Barber%20-%20Shens%20A%20Lady%20(Paul%20Anka).flac&metadata=false Mar 28 13:45:12 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/09 - Patricia Barber - Shens A Lady (Paul Anka).flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/10 - Patricia Barber - Love, Put On Your Faces.flac in service mpd Mar 28 13:45:12 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/FLAC/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%20(1998)%20-%20FLAC%2F10%20-%20Patricia%20Barber%20-%20Love%2C%20Put%20On%20Your%20Faces.flac&metadata=false Mar 28 13:45:12 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/10 - Patricia Barber - Love, Put On Your Faces.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/11 - Patricia Barber - Postmodern Blues.flac in service mpd Mar 28 13:45:12 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/FLAC/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%20(1998)%20-%20FLAC%2F11%20-%20Patricia%20Barber%20-%20Postmodern%20Blues.flac&metadata=false Mar 28 13:45:12 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/11 - Patricia Barber - Postmodern Blues.flac Mar 28 13:45:12 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/12 - Patricia Barber - Let It Rain - Vamp.flac in service mpd Mar 28 13:45:12 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/FLAC/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%20(1998)%20-%20FLAC%2F12%20-%20Patricia%20Barber%20-%20Let%20It%20Rain%20-%20Vamp.flac&metadata=false Mar 28 13:45:12 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC/12 - Patricia Barber - Let It Rain - Vamp.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 28 13:45:18 volumio4 volumio[1539]: info: CURURI: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool Mar 28 13:45:18 volumio4 volumio[1539]: info: Preload queue cleared Mar 28 13:45:18 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/01 - Touch Of Trash.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/02 - Winter.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/03 - You & The Night & The Music.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/04 - Constantinople.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/05 - Light My Fire.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/06 - Silent Partner.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/07 - Company.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/08 - Let It Rain.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/09 - She's A Lady.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/10 - Love, Put On Your Faces.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/11 - Postmodern Blues.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/12 - Let It Rain - Vamp.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Preloading song: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/Patricia Barber - Modern Cool.m3u Mar 28 13:45:18 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/01 - Touch Of Trash.flac in service mpd Mar 28 13:45:18 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/Modern%20Cool/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%2F01%20-%20Touch%20Of%20Trash.flac&metadata=false Mar 28 13:45:18 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/01 - Touch Of Trash.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/02 - Winter.flac in service mpd Mar 28 13:45:18 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/Modern%20Cool/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%2F02%20-%20Winter.flac&metadata=false Mar 28 13:45:18 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/02 - Winter.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/03 - You & The Night & The Music.flac in service mpd Mar 28 13:45:18 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/Modern%20Cool/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%2F03%20-%20You%20%26%20The%20Night%20%26%20The%20Music.flac&metadata=false Mar 28 13:45:18 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/03 - You & The Night & The Music.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/04 - Constantinople.flac in service mpd Mar 28 13:45:18 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/Modern%20Cool/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%2F04%20-%20Constantinople.flac&metadata=false Mar 28 13:45:18 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/04 - Constantinople.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/05 - Light My Fire.flac in service mpd Mar 28 13:45:18 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/Modern%20Cool/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%2F05%20-%20Light%20My%20Fire.flac&metadata=false Mar 28 13:45:18 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/05 - Light My Fire.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/06 - Silent Partner.flac in service mpd Mar 28 13:45:18 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/Modern%20Cool/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%2F06%20-%20Silent%20Partner.flac&metadata=false Mar 28 13:45:18 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/06 - Silent Partner.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/07 - Company.flac in service mpd Mar 28 13:45:18 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/Modern%20Cool/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%2F07%20-%20Company.flac&metadata=false Mar 28 13:45:18 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/07 - Company.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/08 - Let It Rain.flac in service mpd Mar 28 13:45:18 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/Modern%20Cool/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%2F08%20-%20Let%20It%20Rain.flac&metadata=false Mar 28 13:45:18 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/08 - Let It Rain.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/09 - She's A Lady.flac in service mpd Mar 28 13:45:18 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/Modern%20Cool/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%2F09%20-%20She's%20A%20Lady.flac&metadata=false Mar 28 13:45:18 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/09 - She's A Lady.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/10 - Love, Put On Your Faces.flac in service mpd Mar 28 13:45:18 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/Modern%20Cool/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%2F10%20-%20Love%2C%20Put%20On%20Your%20Faces.flac&metadata=false Mar 28 13:45:18 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/10 - Love, Put On Your Faces.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/11 - Postmodern Blues.flac in service mpd Mar 28 13:45:18 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/Modern%20Cool/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%2F11%20-%20Postmodern%20Blues.flac&metadata=false Mar 28 13:45:18 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/11 - Postmodern Blues.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/12 - Let It Rain - Vamp.flac in service mpd Mar 28 13:45:18 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Patricia%20Barber/Modern%20Cool/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPatricia%20Barber%20-%20Modern%20Cool%2F12%20-%20Let%20It%20Rain%20-%20Vamp.flac&metadata=false Mar 28 13:45:18 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/12 - Let It Rain - Vamp.flac Mar 28 13:45:18 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool/Patricia Barber - Modern Cool.m3u in service mpd Mar 28 13:45:19 volumio4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 24. Mar 28 13:45:19 volumio4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 28 13:45:19 volumio4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 28 13:45:19 volumio4 upmpdcli[3177]: Could not open config: /tmp/upmpdcli.conf Mar 28 13:45:19 volumio4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 28 13:45:19 volumio4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 28 13:45:28 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 28 13:45:28 volumio4 volumio[1539]: info: CURURI: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool Mar 28 13:45:28 volumio4 volumio[1539]: info: Preload queue cleared Mar 28 13:45:31 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 28 13:45:31 volumio4 volumio[1539]: info: CURURI: music-library/NAS/Asustor_FLAC/Patricia Barber - Modern Cool (1998) - FLAC Mar 28 13:45:31 volumio4 volumio[1539]: info: Preload queue cleared Mar 28 13:45:34 volumio4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 25. Mar 28 13:45:34 volumio4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 28 13:45:34 volumio4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 28 13:45:34 volumio4 upmpdcli[3194]: Could not open config: /tmp/upmpdcli.conf Mar 28 13:45:34 volumio4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 28 13:45:34 volumio4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 28 13:45:43 volumio4 volumio[1539]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 13:45:43 volumio4 volumio[1539]: info: CoreStateMachine::ClearQueue Mar 28 13:45:43 volumio4 volumio[1539]: info: CoreStateMachine::stop Mar 28 13:45:43 volumio4 volumio[1539]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:45:43 volumio4 volumio[1539]: info: CoreStateMachine::stPlaybackTimer Mar 28 13:45:43 volumio4 volumio[1539]: info: CoreStateMachine::updateTrackBlock Mar 28 13:45:43 volumio4 volumio[1539]: info: CorePlayQueue::getTrackBlock Mar 28 13:45:43 volumio4 volumio[1539]: info: CoreStateMachine::pushState Mar 28 13:45:43 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:45:43 volumio4 volumio[1539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:45:43 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushState Mar 28 13:45:43 volumio4 volumio[1539]: info: CoreStateMachine::serviceStop Mar 28 13:45:43 volumio4 volumio[1539]: info: CorePlayQueue::getTrack 6 Mar 28 13:45:43 volumio4 volumio[1539]: info: CoreCommandRouter::serviceStop Mar 28 13:45:43 volumio4 volumio[1539]: info: ControllerMpd::stop Mar 28 13:45:43 volumio4 volumio[1539]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 13:45:43 volumio4 volumio[1539]: info: CorePlayQueue::clearPlayQueue Mar 28 13:45:43 volumio4 volumio[1539]: info: CorePlayQueue::saveQueue Mar 28 13:45:43 volumio4 volumio[1539]: info: CoreCommandRouter::volumioPushQueue Mar 28 13:45:43 volumio4 volumio[1539]: info: CoreStateMachine::addQueueItems Mar 28 13:45:43 volumio4 volumio[1539]: info: CorePlayQueue::addQueueItems Mar 28 13:45:43 volumio4 volumio[1539]: info: Preload queue cleared Mar 28 13:45:43 volumio4 volumio[1539]: info: Adding Item to queue: music-library/NAS/Asustor_FLAC/Peter Gabriel - So (Remaster) (DTS) Mar 28 13:45:43 volumio4 volumio[1539]: info: Exploding uri music-library/NAS/Asustor_FLAC/Peter Gabriel - So (Remaster) (DTS) in service mpd Mar 28 13:45:43 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:45:43.934Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:45:43 volumio4 volumio5-onboarding[2371]: time=2026-03-28T13:45:43.934Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:40848,00:00:00:00:00:00%01 @ 0x27b2e10" id="mnt/NAS/Asustor_FLAC/Moby - Play (1999) [FLAC]/07 - Bodyrock.flac" title="7 - Bodyrock" Mar 28 13:45:43 volumio4 volumio[1539]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 28 13:45:43 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Peter%20Gabriel/So%20(Remaster)%20(5.1%20DTS)/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPeter%20Gabriel%20-%20So%20(Remaster)%20(DTS)%2FPeter%20Gabriel%20-%20So%20(Remaster)%20(5.1%20DTS).cue&metadata=false Mar 28 13:45:43 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Peter Gabriel - So (Remaster) (DTS)/Peter Gabriel - So (Remaster) (5.1 DTS).cue Mar 28 13:45:43 volumio4 volumio[1539]: info: ALBUMART /albumart?cacheid=391&web=Peter%20Gabriel/So%20(Remaster)%20(5.1%20DTS)/extralarge&path=%2Fmnt%2FNAS%2FAsustor_FLAC%2FPeter%20Gabriel%20-%20So%20(Remaster)%20(DTS)%2FPeter%20Gabriel%20-%20So%20(Remaster)%20(5.1%20DTS).cue&metadata=false Mar 28 13:45:43 volumio4 volumio[1539]: info: URI /mnt/NAS/Asustor_FLAC/Peter Gabriel - So (Remaster) (DTS)/Peter Gabriel - So (Remaster) (5.1 DTS).cue Mar 28 13:45:43 volumio4 volumio[1539]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 13:45:43 volumio4 volumio[1539]: Error: Unable to resolve or reject the same promise twice Mar 28 13:45:43 volumio4 volumio[1539]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 28 13:45:43 volumio4 volumio[1539]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Mar 28 13:45:43 volumio4 volumio[1539]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Mar 28 13:45:43 volumio4 volumio[1539]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Mar 28 13:45:43 volumio4 volumio[1539]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 28 13:45:43 volumio4 volumio[1539]: at Socket.emit (node:events:514:28) Mar 28 13:45:43 volumio4 volumio[1539]: at addChunk (node:internal/streams/readable:343:12) Mar 28 13:45:43 volumio4 volumio[1539]: at readableAddChunk (node:internal/streams/readable:312:11) Mar 28 13:45:43 volumio4 volumio[1539]: at Readable.push (node:internal/streams/readable:253:10) Mar 28 13:45:43 volumio4 volumio[1539]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Mar 28 13:45:43 volumio4 volumio[1539]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 13:45:44 volumio4 sudo[3236]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 13:44' Mar 28 13:45:44 volumio4 sudo[3236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"