Mar 27 13:16:03 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 27 13:16:03 volumio volumio[1223]: info: CURURI: albums://Van%20Morrison/Avalon%20Sunset Mar 27 13:16:03 volumio volumio[1223]: info: Preload queue cleared Mar 27 13:16:03 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/01 Whenever God Shines His Light.flac Mar 27 13:16:03 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/02 Contacting My Angel.flac Mar 27 13:16:03 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/03 I'd Love To Write Another Song.flac Mar 27 13:16:03 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/04 Have I Told You Lately.flac Mar 27 13:16:03 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/05 Coney Island.flac Mar 27 13:16:03 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/06 I'm Tired Joey Boy.flac Mar 27 13:16:03 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/07 When Will I Ever Learn To Live In God.flac Mar 27 13:16:03 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/08 Orangefield.flac Mar 27 13:16:03 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/09 Daring Night.flac Mar 27 13:16:03 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/10 These Are The Days.flac Mar 27 13:16:03 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/01 Whenever God Shines His Light.flac in service mpd Mar 27 13:16:03 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Avalon%20Sunset/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F1989%20Avalon%20Sunset%2F01%20Whenever%20God%20Shines%20His%20Light.flac&metadata=false Mar 27 13:16:03 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/01 Whenever God Shines His Light.flac Mar 27 13:16:03 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/02 Contacting My Angel.flac in service mpd Mar 27 13:16:03 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Avalon%20Sunset/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F1989%20Avalon%20Sunset%2F02%20Contacting%20My%20Angel.flac&metadata=false Mar 27 13:16:03 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/02 Contacting My Angel.flac Mar 27 13:16:03 volumio volumio[1223]: info: No valid Plugin REST Endpoint Mar 27 13:16:03 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/03 I'd Love To Write Another Song.flac in service mpd Mar 27 13:16:03 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Avalon%20Sunset/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F1989%20Avalon%20Sunset%2F03%20I'd%20Love%20To%20Write%20Another%20Song.flac&metadata=false Mar 27 13:16:03 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/03 I'd Love To Write Another Song.flac Mar 27 13:16:03 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/04 Have I Told You Lately.flac in service mpd Mar 27 13:16:03 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Avalon%20Sunset/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F1989%20Avalon%20Sunset%2F04%20Have%20I%20Told%20You%20Lately.flac&metadata=false Mar 27 13:16:03 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/04 Have I Told You Lately.flac Mar 27 13:16:03 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/05 Coney Island.flac in service mpd Mar 27 13:16:03 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Avalon%20Sunset/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F1989%20Avalon%20Sunset%2F05%20Coney%20Island.flac&metadata=false Mar 27 13:16:03 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/05 Coney Island.flac Mar 27 13:16:03 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/06 I'm Tired Joey Boy.flac in service mpd Mar 27 13:16:03 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Avalon%20Sunset/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F1989%20Avalon%20Sunset%2F06%20I'm%20Tired%20Joey%20Boy.flac&metadata=false Mar 27 13:16:03 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/06 I'm Tired Joey Boy.flac Mar 27 13:16:04 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/07 When Will I Ever Learn To Live In God.flac in service mpd Mar 27 13:16:04 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Avalon%20Sunset/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F1989%20Avalon%20Sunset%2F07%20When%20Will%20I%20Ever%20Learn%20To%20Live%20In%20God.flac&metadata=false Mar 27 13:16:04 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/07 When Will I Ever Learn To Live In God.flac Mar 27 13:16:04 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/08 Orangefield.flac in service mpd Mar 27 13:16:04 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Avalon%20Sunset/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F1989%20Avalon%20Sunset%2F08%20Orangefield.flac&metadata=false Mar 27 13:16:04 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/08 Orangefield.flac Mar 27 13:16:04 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/09 Daring Night.flac in service mpd Mar 27 13:16:04 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Avalon%20Sunset/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F1989%20Avalon%20Sunset%2F09%20Daring%20Night.flac&metadata=false Mar 27 13:16:04 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/09 Daring Night.flac Mar 27 13:16:04 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/10 These Are The Days.flac in service mpd Mar 27 13:16:04 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Avalon%20Sunset/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F1989%20Avalon%20Sunset%2F10%20These%20Are%20The%20Days.flac&metadata=false Mar 27 13:16:04 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/10 These Are The Days.flac Mar 27 13:16:05 volumio volumio[1223]: info: Preload queue cleared Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::ClearQueue Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::stop Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::stPlaybackTimer Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::updateTrackBlock Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrackBlock Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 3 Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::serviceStop Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 3 Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::serviceStop Mar 27 13:16:05 volumio volumio[1223]: info: ControllerMpd::stop Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand stop Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::clearPlayQueue Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::saveQueue Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPushQueue Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::addQueueItems Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::addQueueItems Mar 27 13:16:05 volumio volumio[1223]: info: Preload queue cleared Mar 27 13:16:05 volumio volumio[1223]: info: Adding Item to queue: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/01 Whenever God Shines His Light.flac Mar 27 13:16:05 volumio volumio[1223]: info: Using cached record of: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/01 Whenever God Shines His Light.flac Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPushQueue Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::saveQueue Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::updateTrackBlock Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrackBlock Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPlay Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::play index 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::addQueueItems Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::addQueueItems Mar 27 13:16:05 volumio volumio[1223]: info: Preload queue cleared Mar 27 13:16:05 volumio volumio[1223]: info: Adding Item to queue: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/02 Contacting My Angel.flac Mar 27 13:16:05 volumio volumio[1223]: info: Using cached record of: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/02 Contacting My Angel.flac Mar 27 13:16:05 volumio volumio[1223]: info: Adding Item to queue: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/03 I'd Love To Write Another Song.flac Mar 27 13:16:05 volumio volumio[1223]: info: Using cached record of: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/03 I'd Love To Write Another Song.flac Mar 27 13:16:05 volumio volumio[1223]: info: Adding Item to queue: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/04 Have I Told You Lately.flac Mar 27 13:16:05 volumio volumio[1223]: info: Using cached record of: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/04 Have I Told You Lately.flac Mar 27 13:16:05 volumio volumio[1223]: info: Adding Item to queue: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/05 Coney Island.flac Mar 27 13:16:05 volumio volumio[1223]: info: Using cached record of: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/05 Coney Island.flac Mar 27 13:16:05 volumio volumio[1223]: info: Adding Item to queue: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/06 I'm Tired Joey Boy.flac Mar 27 13:16:05 volumio volumio[1223]: info: Using cached record of: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/06 I'm Tired Joey Boy.flac Mar 27 13:16:05 volumio volumio[1223]: info: Adding Item to queue: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/07 When Will I Ever Learn To Live In God.flac Mar 27 13:16:05 volumio volumio[1223]: info: Using cached record of: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/07 When Will I Ever Learn To Live In God.flac Mar 27 13:16:05 volumio volumio[1223]: info: Adding Item to queue: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/08 Orangefield.flac Mar 27 13:16:05 volumio volumio[1223]: info: Using cached record of: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/08 Orangefield.flac Mar 27 13:16:05 volumio volumio[1223]: info: Adding Item to queue: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/09 Daring Night.flac Mar 27 13:16:05 volumio volumio[1223]: info: Using cached record of: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/09 Daring Night.flac Mar 27 13:16:05 volumio volumio[1223]: info: Adding Item to queue: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/10 These Are The Days.flac Mar 27 13:16:05 volumio volumio[1223]: info: Using cached record of: music-library/USB/T7-4TB/Van Morrison/1989 Avalon Sunset/10 These Are The Days.flac Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::stop Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPushQueue Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::saveQueue Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::play index undefined Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::updateTrackBlock Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrackBlock Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::startPlaybackTimer Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::clearAddPlayTracks USB/T7-4TB/Van Morrison/1989 Avalon Sunset/01 Whenever God Shines His Light.flac Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand stop Mar 27 13:16:05 volumio volumio[1223]: info: Mar 27 13:16:05 volumio volumio[1223]: ---------------------------- MPD announces state update: player Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand stop took 30 milliseconds Mar 27 13:16:05 volumio volumio[1223]: info: ControllerMpd::getState Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand stop took 8 milliseconds Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand clear Mar 27 13:16:05 volumio volumio[1223]: info: Mar 27 13:16:05 volumio volumio[1223]: ---------------------------- MPD announces state update: player Mar 27 13:16:05 volumio volumio[1223]: info: ControllerMpd::getState Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Mar 27 13:16:05 volumio volumio[1223]: info: Mar 27 13:16:05 volumio volumio[1223]: ---------------------------- MPD announces state update: player Mar 27 13:16:05 volumio volumio[1223]: info: ControllerMpd::getState Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Mar 27 13:16:05 volumio volumio[1223]: info: Mar 27 13:16:05 volumio volumio[1223]: ---------------------------- MPD announces system playlist update Mar 27 13:16:05 volumio volumio[1223]: info: Ignoring MPD Status Update Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand status took 3 milliseconds Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand clear took 3 milliseconds Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand status took 3 milliseconds Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand status took 2 milliseconds Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::parseState Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand add "USB/T7-4TB/Van Morrison/1989 Avalon Sunset/01 Whenever God Shines His Light.flac" Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::parseState Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::parseState Mar 27 13:16:05 volumio volumio[1223]: info: ControllerMpd::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::servicePushState Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: 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 27 13:16:05 volumio volumio[1223]: verbose: CURRENT POSITION 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::syncState stateService stop Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::syncState currentStatus stop Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Mar 27 13:16:05 volumio volumio[1223]: info: No code Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Mar 27 13:16:05 volumio volumio[1223]: info: ControllerMpd::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::servicePushState Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: 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 27 13:16:05 volumio volumio[1223]: verbose: CURRENT POSITION 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::syncState stateService stop Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::syncState currentStatus stop Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Mar 27 13:16:05 volumio volumio[1223]: info: No code Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Mar 27 13:16:05 volumio volumio[1223]: info: ------------------------------ 35ms Mar 27 13:16:05 volumio volumio[1223]: info: ------------------------------ 34ms Mar 27 13:16:05 volumio volumio[1223]: info: Mar 27 13:16:05 volumio volumio[1223]: ---------------------------- MPD announces system playlist update Mar 27 13:16:05 volumio volumio[1223]: info: Ignoring MPD Status Update Mar 27 13:16:05 volumio volumio[1223]: info: Mar 27 13:16:05 volumio volumio[1223]: ---------------------------- MPD announces system playlist update Mar 27 13:16:05 volumio volumio[1223]: info: Ignoring MPD Status Update Mar 27 13:16:05 volumio volumio[1223]: error: updateQueue error: null Mar 27 13:16:05 volumio volumio[1223]: info: Mar 27 13:16:05 volumio volumio[1223]: ---------------------------- MPD announces system playlist update Mar 27 13:16:05 volumio volumio[1223]: info: Ignoring MPD Status Update Mar 27 13:16:05 volumio volumio[1223]: info: ------------------------------ 37ms Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand playlistinfo took 37 milliseconds Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand add "USB/T7-4TB/Van Morrison/1989 Avalon Sunset/01 Whenever God Shines His Light.flac" took 37 milliseconds Mar 27 13:16:05 volumio volumio[1223]: info: ------------------------------ 4ms Mar 27 13:16:05 volumio volumio[1223]: info: ------------------------------ 3ms Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::parseTrackInfo Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand play Mar 27 13:16:05 volumio volumio[1223]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 27 13:16:05 volumio volumio[1223]: info: ------------------------------ 42ms Mar 27 13:16:05 volumio volumio[1223]: info: Mar 27 13:16:05 volumio volumio[1223]: ---------------------------- MPD announces system playlist update Mar 27 13:16:05 volumio volumio[1223]: info: Ignoring MPD Status Update Mar 27 13:16:05 volumio volumio[1223]: info: Mar 27 13:16:05 volumio volumio[1223]: ---------------------------- MPD announces system playlist update Mar 27 13:16:05 volumio volumio[1223]: info: Ignoring MPD Status Update Mar 27 13:16:05 volumio volumio[1223]: info: Mar 27 13:16:05 volumio volumio[1223]: ---------------------------- MPD announces state update: player Mar 27 13:16:05 volumio volumio[1223]: info: ------------------------------ 28ms Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand play took 26 milliseconds Mar 27 13:16:05 volumio volumio[1223]: info: ------------------------------ 25ms Mar 27 13:16:05 volumio volumio[1223]: info: ------------------------------ 25ms Mar 27 13:16:05 volumio volumio[1223]: info: ControllerMpd::getState Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Mar 27 13:16:05 volumio volumio[1223]: info: Mar 27 13:16:05 volumio volumio[1223]: ---------------------------- MPD announces state update: player Mar 27 13:16:05 volumio volumio[1223]: info: ControllerMpd::getState Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Mar 27 13:16:05 volumio volumio[1223]: info: Mar 27 13:16:05 volumio volumio[1223]: ---------------------------- MPD announces state update: player Mar 27 13:16:05 volumio volumio[1223]: info: ControllerMpd::getState Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Mar 27 13:16:05 volumio volumio[1223]: info: Mar 27 13:16:05 volumio volumio[1223]: ---------------------------- MPD announces state update: player Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand status took 42 milliseconds Mar 27 13:16:05 volumio volumio[1223]: info: ControllerMpd::getState Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::parseState Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand status took 42 milliseconds Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand status took 42 milliseconds Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::parseState Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::parseState Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 13:16:05 volumio volumio[1223]: info: Mar 27 13:16:05 volumio volumio[1223]: ---------------------------- MPD announces state update: player Mar 27 13:16:05 volumio volumio[1223]: info: ControllerMpd::getState Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Mar 27 13:16:05 volumio volumio[1223]: info: Mar 27 13:16:05 volumio volumio[1223]: ---------------------------- MPD announces state update: player Mar 27 13:16:05 volumio volumio[1223]: info: ControllerMpd::getState Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand status took 4 milliseconds Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand status took 3 milliseconds Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::parseState Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::parseTrackInfo Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::parseTrackInfo Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::parseTrackInfo Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::parseState Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 13:16:05 volumio volumio[1223]: info: ControllerMpd::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::servicePushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":295,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Whenever God Shines His Light","artist":"Van Morrison","album":"Avalon Sunset","uri":"USB/T7-4TB/Van Morrison/1989 Avalon Sunset/01 Whenever God Shines His Light.flac","trackType":"flac"} Mar 27 13:16:05 volumio volumio[1223]: verbose: CURRENT POSITION 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::syncState stateService play Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::syncState currentStatus stop Mar 27 13:16:05 volumio volumio[1223]: info: ControllerMpd::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::servicePushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":295,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"Whenever God Shines His Light","artist":"Van Morrison","album":"Avalon Sunset","uri":"USB/T7-4TB/Van Morrison/1989 Avalon Sunset/01 Whenever God Shines His Light.flac","trackType":"flac"} Mar 27 13:16:05 volumio volumio[1223]: verbose: CURRENT POSITION 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::syncState stateService play Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::syncState currentStatus play Mar 27 13:16:05 volumio volumio[1223]: info: Received an update from plugin. extracting info from payload Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Mar 27 13:16:05 volumio volumio[1223]: info: ControllerMpd::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::servicePushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":252,"duration":295,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"437 Kbps","isStreaming":false,"title":"Whenever God Shines His Light","artist":"Van Morrison","album":"Avalon Sunset","uri":"USB/T7-4TB/Van Morrison/1989 Avalon Sunset/01 Whenever God Shines His Light.flac","trackType":"flac"} Mar 27 13:16:05 volumio volumio[1223]: verbose: CURRENT POSITION 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::syncState stateService play Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::syncState currentStatus play Mar 27 13:16:05 volumio volumio[1223]: info: Received an update from plugin. extracting info from payload Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Mar 27 13:16:05 volumio volumio[1223]: info: ------------------------------ 61ms Mar 27 13:16:05 volumio volumio[1223]: info: ------------------------------ 77ms Mar 27 13:16:05 volumio volumio[1223]: info: ------------------------------ 76ms Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand status took 32 milliseconds Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand playlistinfo took 30 milliseconds Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand playlistinfo took 30 milliseconds Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::parseState Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::parseTrackInfo Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::parseTrackInfo Mar 27 13:16:05 volumio volumio[1223]: info: ControllerMpd::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::servicePushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":295,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"667 Kbps","isStreaming":false,"title":"Whenever God Shines His Light","artist":"Van Morrison","album":"Avalon Sunset","uri":"USB/T7-4TB/Van Morrison/1989 Avalon Sunset/01 Whenever God Shines His Light.flac","trackType":"flac"} Mar 27 13:16:05 volumio volumio[1223]: verbose: CURRENT POSITION 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::syncState stateService play Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::syncState currentStatus play Mar 27 13:16:05 volumio volumio[1223]: info: Received an update from plugin. extracting info from payload Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Mar 27 13:16:05 volumio volumio[1223]: info: ControllerMpd::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::servicePushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":295,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"667 Kbps","isStreaming":false,"title":"Whenever God Shines His Light","artist":"Van Morrison","album":"Avalon Sunset","uri":"USB/T7-4TB/Van Morrison/1989 Avalon Sunset/01 Whenever God Shines His Light.flac","trackType":"flac"} Mar 27 13:16:05 volumio volumio[1223]: verbose: CURRENT POSITION 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::syncState stateService play Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::syncState currentStatus play Mar 27 13:16:05 volumio volumio[1223]: info: Received an update from plugin. extracting info from payload Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Mar 27 13:16:05 volumio volumio[1223]: info: ------------------------------ 67ms Mar 27 13:16:05 volumio volumio[1223]: info: ------------------------------ 63ms Mar 27 13:16:05 volumio volumio[1223]: info: sendMpdCommand playlistinfo took 53 milliseconds Mar 27 13:16:05 volumio volumio[1223]: verbose: ControllerMpd::parseTrackInfo Mar 27 13:16:05 volumio volumio[1223]: info: ControllerMpd::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::servicePushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":295,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"667 Kbps","isStreaming":false,"title":"Whenever God Shines His Light","artist":"Van Morrison","album":"Avalon Sunset","uri":"USB/T7-4TB/Van Morrison/1989 Avalon Sunset/01 Whenever God Shines His Light.flac","trackType":"flac"} Mar 27 13:16:05 volumio volumio[1223]: verbose: CURRENT POSITION 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::syncState stateService play Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::syncState currentStatus play Mar 27 13:16:05 volumio volumio[1223]: info: Received an update from plugin. extracting info from payload Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Mar 27 13:16:05 volumio volumio[1223]: info: CoreStateMachine::pushState Mar 27 13:16:05 volumio volumio[1223]: info: CorePlayQueue::getTrack 0 Mar 27 13:16:05 volumio volumio[1223]: info: CoreCommandRouter::volumioPushState Mar 27 13:16:05 volumio volumio[1223]: info: ------------------------------ 102ms Mar 27 13:16:38 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 27 13:16:38 volumio volumio[1223]: info: CURURI: albums://Van%20Morrison/Versatile%20%5B24-96%5D Mar 27 13:16:38 volumio volumio[1223]: info: Preload queue cleared Mar 27 13:16:38 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/2017 Versatile/01 Broken Record.flac Mar 27 13:16:38 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/2017 Versatile/02 A Foggy Day.flac Mar 27 13:16:38 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/2017 Versatile/03 Lets Get Lost.flac Mar 27 13:16:38 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/2017 Versatile/04 Bye Bye Blackbird.flac Mar 27 13:16:38 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/2017 Versatile/05 Skye Boat Song.flac Mar 27 13:16:38 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/2017 Versatile/06 Take It Easy Baby.flac Mar 27 13:16:38 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/2017 Versatile/07 Makin Whoopee.flac Mar 27 13:16:38 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/2017 Versatile/08 I Get A Kick Out Of You.flac Mar 27 13:16:38 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/2017 Versatile/09 I Forgot That Love Existed.flac Mar 27 13:16:38 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/2017 Versatile/10 Unchained Melody.flac Mar 27 13:16:38 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/2017 Versatile/11 Start All Over Again.flac Mar 27 13:16:38 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/2017 Versatile/12 Only A Dream.flac Mar 27 13:16:38 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/2017 Versatile/13 Affirmation.flac Mar 27 13:16:38 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/2017 Versatile/14 The Party's Over.flac Mar 27 13:16:38 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/2017 Versatile/15 I Left My Heart In San Francisco.flac Mar 27 13:16:38 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Van Morrison/2017 Versatile/16 They Can't Take That Away From Me.flac Mar 27 13:16:38 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/2017 Versatile/01 Broken Record.flac in service mpd Mar 27 13:16:38 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Versatile%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F2017%20Versatile%2F01%20Broken%20Record.flac&metadata=false Mar 27 13:16:38 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/2017 Versatile/01 Broken Record.flac Mar 27 13:16:38 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/2017 Versatile/02 A Foggy Day.flac in service mpd Mar 27 13:16:38 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Versatile%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F2017%20Versatile%2F02%20A%20Foggy%20Day.flac&metadata=false Mar 27 13:16:38 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/2017 Versatile/02 A Foggy Day.flac Mar 27 13:16:38 volumio volumio[1223]: info: No valid Plugin REST Endpoint Mar 27 13:16:38 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/2017 Versatile/03 Lets Get Lost.flac in service mpd Mar 27 13:16:38 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Versatile%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F2017%20Versatile%2F03%20Lets%20Get%20Lost.flac&metadata=false Mar 27 13:16:38 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/2017 Versatile/03 Lets Get Lost.flac Mar 27 13:16:38 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/2017 Versatile/04 Bye Bye Blackbird.flac in service mpd Mar 27 13:16:38 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Versatile%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F2017%20Versatile%2F04%20Bye%20Bye%20Blackbird.flac&metadata=false Mar 27 13:16:38 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/2017 Versatile/04 Bye Bye Blackbird.flac Mar 27 13:16:38 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/2017 Versatile/05 Skye Boat Song.flac in service mpd Mar 27 13:16:38 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Versatile%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F2017%20Versatile%2F05%20Skye%20Boat%20Song.flac&metadata=false Mar 27 13:16:38 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/2017 Versatile/05 Skye Boat Song.flac Mar 27 13:16:38 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/2017 Versatile/06 Take It Easy Baby.flac in service mpd Mar 27 13:16:38 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Versatile%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F2017%20Versatile%2F06%20Take%20It%20Easy%20Baby.flac&metadata=false Mar 27 13:16:38 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/2017 Versatile/06 Take It Easy Baby.flac Mar 27 13:16:38 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/2017 Versatile/07 Makin Whoopee.flac in service mpd Mar 27 13:16:38 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Versatile%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F2017%20Versatile%2F07%20Makin%20Whoopee.flac&metadata=false Mar 27 13:16:38 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/2017 Versatile/07 Makin Whoopee.flac Mar 27 13:16:38 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/2017 Versatile/08 I Get A Kick Out Of You.flac in service mpd Mar 27 13:16:38 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Versatile%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F2017%20Versatile%2F08%20I%20Get%20A%20Kick%20Out%20Of%20You.flac&metadata=false Mar 27 13:16:38 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/2017 Versatile/08 I Get A Kick Out Of You.flac Mar 27 13:16:38 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/2017 Versatile/09 I Forgot That Love Existed.flac in service mpd Mar 27 13:16:38 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Versatile%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F2017%20Versatile%2F09%20I%20Forgot%20That%20Love%20Existed.flac&metadata=false Mar 27 13:16:38 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/2017 Versatile/09 I Forgot That Love Existed.flac Mar 27 13:16:38 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/2017 Versatile/10 Unchained Melody.flac in service mpd Mar 27 13:16:38 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Versatile%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F2017%20Versatile%2F10%20Unchained%20Melody.flac&metadata=false Mar 27 13:16:38 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/2017 Versatile/10 Unchained Melody.flac Mar 27 13:16:38 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/2017 Versatile/11 Start All Over Again.flac in service mpd Mar 27 13:16:38 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Versatile%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F2017%20Versatile%2F11%20Start%20All%20Over%20Again.flac&metadata=false Mar 27 13:16:38 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/2017 Versatile/11 Start All Over Again.flac Mar 27 13:16:38 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/2017 Versatile/12 Only A Dream.flac in service mpd Mar 27 13:16:38 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Versatile%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F2017%20Versatile%2F12%20Only%20A%20Dream.flac&metadata=false Mar 27 13:16:38 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/2017 Versatile/12 Only A Dream.flac Mar 27 13:16:38 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/2017 Versatile/13 Affirmation.flac in service mpd Mar 27 13:16:38 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Versatile%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F2017%20Versatile%2F13%20Affirmation.flac&metadata=false Mar 27 13:16:38 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/2017 Versatile/13 Affirmation.flac Mar 27 13:16:38 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/2017 Versatile/14 The Party's Over.flac in service mpd Mar 27 13:16:38 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Versatile%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F2017%20Versatile%2F14%20The%20Party's%20Over.flac&metadata=false Mar 27 13:16:38 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/2017 Versatile/14 The Party's Over.flac Mar 27 13:16:38 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/2017 Versatile/15 I Left My Heart In San Francisco.flac in service mpd Mar 27 13:16:38 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Versatile%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F2017%20Versatile%2F15%20I%20Left%20My%20Heart%20In%20San%20Francisco.flac&metadata=false Mar 27 13:16:38 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/2017 Versatile/15 I Left My Heart In San Francisco.flac Mar 27 13:16:38 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Van Morrison/2017 Versatile/16 They Can't Take That Away From Me.flac in service mpd Mar 27 13:16:38 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Van%20Morrison/Versatile%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FVan%20Morrison%2F2017%20Versatile%2F16%20They%20Can't%20Take%20That%20Away%20From%20Me.flac&metadata=false Mar 27 13:16:38 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Van Morrison/2017 Versatile/16 They Can't Take That Away From Me.flac Mar 27 13:16:50 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 27 13:16:50 volumio volumio[1223]: info: CURURI: albums:// Mar 27 13:16:50 volumio volumio[1223]: info: listAlbums - loading Albums from cache Mar 27 13:16:50 volumio volumio[1223]: info: Preload queue cleared Mar 27 13:17:06 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 27 13:17:06 volumio volumio[1223]: info: CURURI: artists:// Mar 27 13:17:06 volumio volumio[1223]: info: Preload queue cleared Mar 27 13:17:09 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 27 13:17:09 volumio volumio[1223]: info: CURURI: artists://Amy%20Winehouse Mar 27 13:17:09 volumio volumio[1223]: info: Preload queue cleared Mar 27 13:17:09 volumio volumio[1223]: info: Preload queue cleared Mar 27 13:17:09 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Back to Black [24-96]/01 Rehab.flac Mar 27 13:17:09 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Back to Black [24-96]/02 You Know I'm No Good.flac Mar 27 13:17:09 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Back to Black [24-96]/03 Me & Mr Jones.flac Mar 27 13:17:09 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Back to Black [24-96]/04 Just Friends.flac Mar 27 13:17:09 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Back to Black [24-96]/05 Back To Black.flac Mar 27 13:17:09 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Back to Black [24-96]/06 Love Is A Losing Game.flac Mar 27 13:17:09 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Back to Black [24-96]/07 Tears Dry On Their Own.flac Mar 27 13:17:09 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Back to Black [24-96]/08 Wake Up Alone.flac Mar 27 13:17:09 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Back to Black [24-96]/09 Some Unholy War.flac Mar 27 13:17:09 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Back to Black [24-96]/10 He Can Only Hold Her.flac Mar 27 13:17:09 volumio volumio[1223]: info: Preloading song: music-library/USB/T7-4TB/Back to Black [24-96]/11 Addicted.flac Mar 27 13:17:09 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Back to Black [24-96]/01 Rehab.flac in service mpd Mar 27 13:17:09 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Amy%20Winehouse/Back%20to%20Black%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FBack%20to%20Black%20%5B24-96%5D%2F01%20Rehab.flac&metadata=false Mar 27 13:17:09 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Back to Black [24-96]/01 Rehab.flac Mar 27 13:17:09 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Back to Black [24-96]/02 You Know I'm No Good.flac in service mpd Mar 27 13:17:09 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Amy%20Winehouse/Back%20to%20Black%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FBack%20to%20Black%20%5B24-96%5D%2F02%20You%20Know%20I'm%20No%20Good.flac&metadata=false Mar 27 13:17:09 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Back to Black [24-96]/02 You Know I'm No Good.flac Mar 27 13:17:09 volumio volumio[1223]: info: No valid Plugin REST Endpoint Mar 27 13:17:09 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Back to Black [24-96]/03 Me & Mr Jones.flac in service mpd Mar 27 13:17:09 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Amy%20Winehouse/Back%20to%20Black%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FBack%20to%20Black%20%5B24-96%5D%2F03%20Me%20%26%20Mr%20Jones.flac&metadata=false Mar 27 13:17:09 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Back to Black [24-96]/03 Me & Mr Jones.flac Mar 27 13:17:09 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Back to Black [24-96]/04 Just Friends.flac in service mpd Mar 27 13:17:09 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Amy%20Winehouse/Back%20to%20Black%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FBack%20to%20Black%20%5B24-96%5D%2F04%20Just%20Friends.flac&metadata=false Mar 27 13:17:09 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Back to Black [24-96]/04 Just Friends.flac Mar 27 13:17:09 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Back to Black [24-96]/05 Back To Black.flac in service mpd Mar 27 13:17:09 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Amy%20Winehouse/Back%20to%20Black%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FBack%20to%20Black%20%5B24-96%5D%2F05%20Back%20To%20Black.flac&metadata=false Mar 27 13:17:09 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Back to Black [24-96]/05 Back To Black.flac Mar 27 13:17:09 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Back to Black [24-96]/06 Love Is A Losing Game.flac in service mpd Mar 27 13:17:09 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Amy%20Winehouse/Back%20to%20Black%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FBack%20to%20Black%20%5B24-96%5D%2F06%20Love%20Is%20A%20Losing%20Game.flac&metadata=false Mar 27 13:17:09 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Back to Black [24-96]/06 Love Is A Losing Game.flac Mar 27 13:17:09 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Back to Black [24-96]/07 Tears Dry On Their Own.flac in service mpd Mar 27 13:17:09 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Amy%20Winehouse/Back%20to%20Black%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FBack%20to%20Black%20%5B24-96%5D%2F07%20Tears%20Dry%20On%20Their%20Own.flac&metadata=false Mar 27 13:17:09 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Back to Black [24-96]/07 Tears Dry On Their Own.flac Mar 27 13:17:09 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Back to Black [24-96]/08 Wake Up Alone.flac in service mpd Mar 27 13:17:09 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Amy%20Winehouse/Back%20to%20Black%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FBack%20to%20Black%20%5B24-96%5D%2F08%20Wake%20Up%20Alone.flac&metadata=false Mar 27 13:17:09 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Back to Black [24-96]/08 Wake Up Alone.flac Mar 27 13:17:09 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Back to Black [24-96]/09 Some Unholy War.flac in service mpd Mar 27 13:17:09 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Amy%20Winehouse/Back%20to%20Black%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FBack%20to%20Black%20%5B24-96%5D%2F09%20Some%20Unholy%20War.flac&metadata=false Mar 27 13:17:09 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Back to Black [24-96]/09 Some Unholy War.flac Mar 27 13:17:09 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Back to Black [24-96]/10 He Can Only Hold Her.flac in service mpd Mar 27 13:17:09 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Amy%20Winehouse/Back%20to%20Black%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FBack%20to%20Black%20%5B24-96%5D%2F10%20He%20Can%20Only%20Hold%20Her.flac&metadata=false Mar 27 13:17:09 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Back to Black [24-96]/10 He Can Only Hold Her.flac Mar 27 13:17:09 volumio volumio[1223]: info: Exploding uri music-library/USB/T7-4TB/Back to Black [24-96]/11 Addicted.flac in service mpd Mar 27 13:17:09 volumio volumio[1223]: info: ALBUMART /albumart?cacheid=874&web=Amy%20Winehouse/Back%20to%20Black%20%5B24-96%5D/extralarge&path=%2Fmnt%2FUSB%2FT7-4TB%2FBack%20to%20Black%20%5B24-96%5D%2F11%20Addicted.flac&metadata=false Mar 27 13:17:09 volumio volumio[1223]: info: URI /mnt/USB/T7-4TB/Back to Black [24-96]/11 Addicted.flac Mar 27 13:17:11 volumio volumio[1223]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 13:17:11 volumio volumio[1223]: TypeError: Cannot read properties of null (reading 'slice') Mar 27 13:17:11 volumio volumio[1223]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 27 13:17:11 volumio volumio[1223]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 27 13:17:11 volumio volumio[1223]: at Object.onceWrapper (node:events:629:26) Mar 27 13:17:11 volumio volumio[1223]: at ClientRequest.emit (node:events:514:28) Mar 27 13:17:11 volumio volumio[1223]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Mar 27 13:17:11 volumio volumio[1223]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Mar 27 13:17:11 volumio volumio[1223]: at TLSSocket.socketOnData (node:_http_client:535:22) Mar 27 13:17:11 volumio volumio[1223]: at TLSSocket.emit (node:events:514:28) Mar 27 13:17:11 volumio volumio[1223]: at addChunk (node:internal/streams/readable:343:12) Mar 27 13:17:11 volumio volumio[1223]: at readableAddChunk (node:internal/streams/readable:316:9) Mar 27 13:17:11 volumio volumio[1223]: at Readable.push (node:internal/streams/readable:253:10) Mar 27 13:17:11 volumio volumio[1223]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Mar 27 13:17:11 volumio volumio[1223]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 13:17:11 volumio sudo[3318]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-27 13:16' Mar 27 13:17:11 volumio sudo[3318]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 13:17:12 volumio sudo[3318]: pam_unix(sudo:session): session closed for user root Mar 27 13:17:12 volumio volumio-remote-updater[802]: [2026-03-27 13:17:12] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 27 13:17:12 volumio volumio-remote-updater[802]: [2026-03-27 13:17:12] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 27 13:17:12 volumio volumio5-onboarding[1506]: time=2026-03-27T13:17:12.239+01:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Mar 27 13:17:12 volumio volumio5-onboarding[1506]: time=2026-03-27T13:17:12.240+01:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Mar 27 13:17:12 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 27 13:17:12 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 27 13:17:12 volumio systemd[1]: volumio.service: Consumed 9min 22.956s CPU time. Mar 27 13:17:12 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Mar 27 13:17:12 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Mar 27 13:17:12 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Mar 27 13:17:12 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Mar 27 13:17:12 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Mar 27 13:17:12 volumio systemd[1]: volumio.service: Consumed 9min 22.956s CPU time. Mar 27 13:17:12 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Mar 27 13:17:12 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Mar 27 13:17:13 volumio volumio[3362]: info: ------------------------------------------- Mar 27 13:17:13 volumio volumio[3362]: info: ----- Volumio3 ---- Mar 27 13:17:13 volumio volumio[3362]: info: ------------------------------------------- Mar 27 13:17:13 volumio volumio[3362]: info: ----- System startup ---- Mar 27 13:17:13 volumio volumio[3362]: info: ------------------------------------------- Mar 27 13:17:13 volumio volumio[3362]: info: MYVOLUMIO Environment detected Mar 27 13:17:13 volumio volumio[3362]: info: Plugin folders cleanup Mar 27 13:17:13 volumio volumio[3362]: info: Scanning into folder /volumio/app/plugins/ Mar 27 13:17:13 volumio volumio[3362]: info: Scanning category audio_interface Mar 27 13:17:13 volumio volumio[3362]: info: Scanning category miscellanea Mar 27 13:17:13 volumio volumio[3362]: info: Scanning category music_service Mar 27 13:17:13 volumio volumio[3362]: info: Scanning category plugins.json Mar 27 13:17:13 volumio volumio[3362]: info: Scanning category system_controller Mar 27 13:17:13 volumio volumio[3362]: info: Scanning category user_interface Mar 27 13:17:13 volumio volumio[3362]: info: Scanning into folder /data/plugins/ Mar 27 13:17:13 volumio volumio[3362]: info: Plugin folders cleanup completed Mar 27 13:17:13 volumio volumio[3362]: info: ------------------------------------------- Mar 27 13:17:13 volumio volumio[3362]: info: ----- Core plugins startup ---- Mar 27 13:17:13 volumio volumio[3362]: info: ------------------------------------------- Mar 27 13:17:13 volumio volumio[3362]: info: Loading plugins from folder /volumio/app/plugins/ Mar 27 13:17:13 volumio volumio[3362]: info: Adding plugin upnp to MyMusic Plugins Mar 27 13:17:13 volumio volumio[3362]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 27 13:17:13 volumio volumio[3362]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 27 13:17:13 volumio volumio[3362]: info: Loading plugins from folder /data/plugins/ Mar 27 13:17:13 volumio volumio[3362]: info: Loading plugin "system"... Mar 27 13:17:13 volumio volumio[3362]: info: Loading plugin "appearance"... Mar 27 13:17:14 volumio volumio[3362]: info: Loading plugin "network"... Mar 27 13:17:14 volumio volumio[3362]: info: Refreshing Cached IP Addresses Mar 27 13:17:14 volumio sudo[3390]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 27 13:17:14 volumio sudo[3390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 13:17:14 volumio volumio[3362]: info: Loading plugin "services"... Mar 27 13:17:14 volumio sudo[3392]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 27 13:17:14 volumio sudo[3392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 13:17:14 volumio volumio[3362]: info: Loading plugin "volumio5onboarding"... Mar 27 13:17:14 volumio sudo[3392]: pam_unix(sudo:session): session closed for user root Mar 27 13:17:14 volumio sudo[3390]: pam_unix(sudo:session): session closed for user root Mar 27 13:17:14 volumio volumio[3362]: info: Loading plugin "alsa_controller"... Mar 27 13:17:14 volumio sudo[3399]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 27 13:17:14 volumio sudo[3399]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 13:17:14 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 13:17:14 volumio volumio[3362]: info: Loading plugin "wizard"... Mar 27 13:17:14 volumio volumio[3362]: info: Loading plugin "networkfs"... Mar 27 13:17:14 volumio volumio[3362]: info: Starting Udev Watcher for removable devices Mar 27 13:17:14 volumio volumio[3362]: info: Ignoring mount for partition: boot Mar 27 13:17:14 volumio volumio[3362]: info: Ignoring mount for partition: volumio Mar 27 13:17:14 volumio volumio[3362]: info: Ignoring mount for partition: volumio_data Mar 27 13:17:14 volumio volumio[3362]: error: Cannot associate FS Label, not mounting Mar 27 13:17:14 volumio volumio[3362]: info: Mounting Device T7-4TB Mar 27 13:17:14 volumio sudo[3426]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/T7-4TB -o noatime Mar 27 13:17:14 volumio sudo[3426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 13:17:14 volumio sudo[3426]: pam_unix(sudo:session): session closed for user root Mar 27 13:17:14 volumio volumio[3362]: mount: /media/T7-4TB: /dev/sda2 already mounted on /media/T7-4TB. Mar 27 13:17:14 volumio volumio[3362]: dmesg(1) may have more information after failed mount system call. Mar 27 13:17:14 volumio volumio[3362]: error: Failed to mount T7-4TB: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda2" "/mnt/USB/T7-4TB" -o noatime Mar 27 13:17:14 volumio volumio[3362]: mount: /media/T7-4TB: /dev/sda2 already mounted on /media/T7-4TB. Mar 27 13:17:14 volumio volumio[3362]: dmesg(1) may have more information after failed mount system call. Mar 27 13:17:14 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 27 13:17:14 volumio volumio[3362]: info: Loading plugin "volumio_command_line_client"... Mar 27 13:17:14 volumio volumio[3362]: info: Loading plugin "upnp"... Mar 27 13:17:14 volumio volumio[3362]: info: [1774613834730] Starting Upmpd Daemon Mar 27 13:17:14 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 27 13:17:14 volumio volumio[3362]: info: Loading plugin "my_music"... Mar 27 13:17:14 volumio volumio[3362]: info: Loading plugin "mpd"... Mar 27 13:17:14 volumio volumio[3362]: info: Loading plugin "upnp_browser"... Mar 27 13:17:15 volumio volumio[3362]: info: Starting UPNP Browser Mar 27 13:17:15 volumio volumio[3362]: info: Loading plugin "alarm-clock"... Mar 27 13:17:15 volumio volumio[3362]: info: Loading plugin "airplay_emulation"... Mar 27 13:17:15 volumio volumio[3362]: info: Starting Shairport Sync Mar 27 13:17:15 volumio volumio[3362]: info: Loading plugin "last_100"... Mar 27 13:17:15 volumio volumio[3362]: info: Loading plugin "webradio"... Mar 27 13:17:15 volumio volumio[3362]: info: Loading plugin "i2s_dacs"... Mar 27 13:17:15 volumio volumio[3362]: info: I2S DAC not set, start Auto-detection Mar 27 13:17:15 volumio volumio[3362]: info: Loading plugin "volumiodiscovery"... Mar 27 13:17:15 volumio volumio[3362]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 27 13:17:15 volumio volumio[3362]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 27 13:17:15 volumio volumio[3362]: *** WARNING *** For more information see Mar 27 13:17:15 volumio volumio[3362]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 27 13:17:15 volumio volumio[3362]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 27 13:17:15 volumio node[3362]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 27 13:17:15 volumio volumio[3362]: *** WARNING *** For more information see Mar 27 13:17:15 volumio node[3362]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 27 13:17:15 volumio node[3362]: *** WARNING *** For more information see Mar 27 13:17:15 volumio node[3362]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 27 13:17:15 volumio node[3362]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 27 13:17:15 volumio node[3362]: *** WARNING *** For more information see Mar 27 13:17:15 volumio volumio[3362]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 27 13:17:15 volumio volumio[3362]: info: Discovery: Started advertising with name: Volumio Mar 27 13:17:15 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 27 13:17:15 volumio volumio[3362]: info: Loading plugin "outputs"... Mar 27 13:17:15 volumio volumio[3362]: info: Loading plugin "albumart"... Mar 27 13:17:15 volumio volumio[3362]: info: Plugin example_plugin is not enabled Mar 27 13:17:15 volumio volumio[3362]: info: Loading plugin "inputs"... Mar 27 13:17:15 volumio volumio[3362]: info: Loading plugin "updater_comm"... Mar 27 13:17:16 volumio volumio[3362]: info: Plugin mpdemulation is not enabled Mar 27 13:17:16 volumio volumio[3362]: info: Loading plugin "rest_api"... Mar 27 13:17:16 volumio volumio[3362]: info: Loading plugin "websocket"... Mar 27 13:17:16 volumio volumio[3362]: info: Starting Socket.io Server version 1.7.4 Mar 27 13:17:16 volumio volumio[3362]: info: Loading i18n strings for locale en Mar 27 13:17:16 volumio volumio[3362]: Updating browse sources language Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::initPlayerControls Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 27 13:17:16 volumio volumio[3362]: Express server listening on port 3000 Mar 27 13:17:16 volumio volumio[3362]: [Metrics] WebUI: 3s 22.80ms Mar 27 13:17:16 volumio volumio[3362]: info: CoreStateMachine::resetVolumioState Mar 27 13:17:16 volumio volumio[3362]: info: CoreStateMachine::getcurrentVolume Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::volumioRetrievevolume Mar 27 13:17:16 volumio volumio[3362]: info: CoreStateMachine::pushState Mar 27 13:17:16 volumio volumio[3362]: info: CorePlayQueue::getTrack 0 Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::volumioPushState Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 13:17:16 volumio volumio[3362]: info: Volumio Network Manager: Network status updated: 1 Mar 27 13:17:16 volumio volumio[3430]: Forking 3 albumart workers Mar 27 13:17:16 volumio volumio[3362]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Mar 27 13:17:16 volumio volumio[3362]: verbose: New Socket.io Connection to 10.0.0.27 from 10.0.0.28 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:148.0) Gecko/20100101 Firefox/148.0 Engine version: 3 Transport: polling Total Clients: 1 Mar 27 13:17:16 volumio volumio[3362]: info: Reloading queue from file Mar 27 13:17:16 volumio volumio[3362]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Mar 27 13:17:16 volumio volumio[3362]: info: CoreStateMachine::setRepeat null single undefined Mar 27 13:17:16 volumio volumio[3362]: info: CoreStateMachine::pushState Mar 27 13:17:16 volumio volumio[3362]: info: CorePlayQueue::getTrack 0 Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::volumioPushState Mar 27 13:17:16 volumio volumio[3362]: info: CoreStateMachine::setRandom null Mar 27 13:17:16 volumio volumio[3362]: info: CoreStateMachine::pushState Mar 27 13:17:16 volumio volumio[3362]: info: CorePlayQueue::getTrack 0 Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::volumioPushState Mar 27 13:17:16 volumio volumio[3362]: info: Setting Device type: Raspberry PI Mar 27 13:17:16 volumio volumio[3362]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Mar 27 13:17:16 volumio volumio[3362]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Mar 27 13:17:16 volumio volumio[3362]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Mar 27 13:17:16 volumio volumio[3362]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Mar 27 13:17:16 volumio volumio[3362]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Mar 27 13:17:16 volumio volumio[3362]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Mar 27 13:17:16 volumio volumio[3362]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Mar 27 13:17:16 volumio volumio[3362]: info: Completed loading Core Plugins Mar 27 13:17:16 volumio volumio[3362]: info: Preparing to generate the ALSA configuration file Mar 27 13:17:16 volumio volumio[3362]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Mar 27 13:17:16 volumio volumio[3362]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Mar 27 13:17:16 volumio volumio[3362]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Mar 27 13:17:16 volumio volumio[3362]: info: Asound.conf file unchanged, so no further update is needed Mar 27 13:17:16 volumio volumio[3362]: info: Output device has changed, restarting MPD Mar 27 13:17:16 volumio volumio[3362]: info: Output device has changed, restarting Shairport Sync Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 13:17:16 volumio sudo[3482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 27 13:17:16 volumio sudo[3482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 13:17:16 volumio sudo[3484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 27 13:17:16 volumio sudo[3484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 13:17:16 volumio volumio[3362]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 13:17:16 volumio volumio[3362]: info: ___________ START PLUGINS ___________ Mar 27 13:17:16 volumio sudo[3482]: pam_unix(sudo:session): session closed for user root Mar 27 13:17:16 volumio volumio[3362]: info: ControllerMpd::onStart: Initializing MPD Mar 27 13:17:16 volumio volumio[3362]: info: Creating MPD Configuration file Mar 27 13:17:16 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 27 13:17:16 volumio sudo[3492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 27 13:17:16 volumio volumio[3362]: info: [1774613836791] CoreMusicLibrary::Adding element Media Servers Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 13:17:16 volumio sudo[3492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 13:17:16 volumio sudo[3494]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 27 13:17:16 volumio volumio[3362]: info: UPNP Browser: Client initialized successfully Mar 27 13:17:16 volumio sudo[3494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 13:17:16 volumio sudo[3494]: pam_unix(sudo:session): session closed for user root Mar 27 13:17:16 volumio sudo[3496]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 27 13:17:16 volumio sudo[3496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 13:17:16 volumio sudo[3492]: pam_unix(sudo:session): session closed for user root Mar 27 13:17:16 volumio volumio[3362]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 13:17:16 volumio volumio[3362]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 27 13:17:16 volumio volumio[3362]: info: [1774613836952] CoreMusicLibrary::Adding element Last_100 Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 27 13:17:16 volumio volumio[3362]: info: [1774613836954] CoreMusicLibrary::Adding element Webradio Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 13:17:16 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 13:17:16 volumio volumio[3362]: info: Initializing BBC Radios Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: Volumio Calling Home Mar 27 13:17:17 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 27 13:17:17 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 27 13:17:17 volumio systemd[1]: mpd.service: Consumed 12.776s CPU time. Mar 27 13:17:17 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 27 13:17:17 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 27 13:17:17 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 27 13:17:17 volumio volumio[3443]: Starting albumart workers Mar 27 13:17:17 volumio sudo[3399]: pam_unix(sudo:session): session closed for user root Mar 27 13:17:17 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 27 13:17:17 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 27 13:17:17 volumio volumio-remote-updater[802]: [2026-03-27 13:17:17] [connect] Successful connection Mar 27 13:17:17 volumio volumio[3444]: Starting albumart workers Mar 27 13:17:17 volumio volumio[3445]: Starting albumart workers Mar 27 13:17:17 volumio sudo[3525]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 27 13:17:17 volumio sudo[3525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 27 13:17:17 volumio sudo[3525]: pam_unix(sudo:session): session closed for user root Mar 27 13:17:17 volumio volumio[3362]: verbose: New Socket.io Connection to 10.0.0.27 from 10.0.0.28 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:148.0) Gecko/20100101 Firefox/148.0 Engine version: 3 Transport: polling Total Clients: 2 Mar 27 13:17:17 volumio volumio[3362]: error: Upnp client error: Error: This socket has been ended by the other party Mar 27 13:17:17 volumio volumio[3362]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Mar 27 13:17:17 volumio volumio[3362]: info: MPD Permissions set Mar 27 13:17:17 volumio volumio[3362]: info: MPD Permissions set Mar 27 13:17:17 volumio volumio-remote-updater[802]: [2026-03-27 13:17:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1774613837 101 Mar 27 13:17:17 volumio volumio[3362]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 3 Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 27 13:17:17 volumio volumio[3362]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 27 13:17:17 volumio volumio[3362]: info: Volumio called home Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::volumioGetVisibleSources Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::volumioGetState Mar 27 13:17:17 volumio volumio[3362]: info: CorePlayQueue::getTrack 0 Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::volumioGetQueue Mar 27 13:17:17 volumio volumio[3362]: info: CoreStateMachine::getQueue Mar 27 13:17:17 volumio volumio[3362]: info: CorePlayQueue::getQueue Mar 27 13:17:17 volumio volumio[3362]: info: Listing playlists Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 27 13:17:17 volumio volumio[3362]: info: Received Get System Info Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 13:17:17 volumio volumio[3362]: info: Discovery: Getting this device information Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::volumioGetState Mar 27 13:17:17 volumio volumio[3362]: info: CorePlayQueue::getTrack 0 Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 13:17:17 volumio volumio[3362]: info: CoreCommandRouter::volumioGetState Mar 27 13:17:17 volumio volumio[3362]: info: CorePlayQueue::getTrack 0 Mar 27 13:17:18 volumio volumio[3362]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Mar 27 13:17:18 volumio volumio[3362]: info: Discovery: adding 695a0bc1-2faf-45af-9ee5-d159eb0b850b Mar 27 13:17:18 volumio volumio[3362]: info: Discovery: Found device Volumio Mar 27 13:17:18 volumio volumio[3362]: info: CoreCommandRouter::volumioGetState Mar 27 13:17:18 volumio volumio[3362]: info: CorePlayQueue::getTrack 0 Mar 27 13:17:18 volumio volumio[3362]: info: Starting Shairport Sync Mar 27 13:17:18 volumio volumio[3362]: info: Starting Shairport Sync Mar 27 13:17:18 volumio volumio[3362]: info: Starting Shairport Sync Mar 27 13:17:18 volumio sudo[3559]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 27 13:17:18 volumio sudo[3559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 13:17:18 volumio volumio[3362]: info: Discovery: this is already registered, 695a0bc1-2faf-45af-9ee5-d159eb0b850b Mar 27 13:17:18 volumio volumio[3362]: info: Discovery: Found device Volumio Mar 27 13:17:18 volumio volumio[3362]: info: CoreCommandRouter::volumioGetState Mar 27 13:17:18 volumio volumio[3362]: info: CorePlayQueue::getTrack 0 Mar 27 13:17:18 volumio sudo[3561]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 27 13:17:18 volumio sudo[3561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 13:17:18 volumio sudo[3563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 27 13:17:18 volumio sudo[3563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 13:17:18 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 27 13:17:18 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 27 13:17:18 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 13:17:18 volumio systemd[1]: shairport-sync.service: Consumed 2.783s CPU time. Mar 27 13:17:18 volumio volumio[3362]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Mar 27 13:17:18 volumio volumio[3362]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Mar 27 13:17:18 volumio volumio[3362]: info: Received Get System Info Mar 27 13:17:18 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 13:17:18 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 13:17:18 volumio volumio[3362]: info: Discovery: Getting this device information Mar 27 13:17:18 volumio volumio[3362]: info: CoreCommandRouter::volumioGetState Mar 27 13:17:18 volumio volumio[3362]: info: CorePlayQueue::getTrack 0 Mar 27 13:17:18 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 13:17:18 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 27 13:17:18 volumio volumio[3362]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 27 13:17:18 volumio volumio[3362]: info: CoreCommandRouter::volumioGetState Mar 27 13:17:18 volumio volumio[3362]: info: CorePlayQueue::getTrack 0 Mar 27 13:17:18 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 13:17:18 volumio sudo[3559]: pam_unix(sudo:session): session closed for user root Mar 27 13:17:18 volumio sudo[3563]: pam_unix(sudo:session): session closed for user root Mar 27 13:17:18 volumio sudo[3561]: pam_unix(sudo:session): session closed for user root Mar 27 13:17:18 volumio volumio[3362]: info: Shairport-Sync Started Mar 27 13:17:18 volumio volumio[3362]: Error adding Membership: Error: addMembership EINVAL Mar 27 13:17:18 volumio volumio[3362]: info: Shairport-Sync Started Mar 27 13:17:18 volumio volumio[3362]: info: Shairport-Sync Started Mar 27 13:17:18 volumio volumio[3362]: info: Preload queue cleared Mar 27 13:17:18 volumio volumio[3362]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 27 13:17:18 volumio volumio[3362]: info: CoreStateMachine::ClearQueue Mar 27 13:17:18 volumio volumio[3362]: info: CoreStateMachine::stop Mar 27 13:17:18 volumio volumio[3362]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 13:17:18 volumio volumio[3362]: info: CorePlayQueue::clearPlayQueue Mar 27 13:17:18 volumio volumio[3362]: info: CorePlayQueue::saveQueue Mar 27 13:17:18 volumio volumio[3362]: info: CoreCommandRouter::volumioPushQueue Mar 27 13:17:18 volumio volumio[3362]: info: CoreStateMachine::addQueueItems Mar 27 13:17:18 volumio volumio[3362]: info: CorePlayQueue::addQueueItems Mar 27 13:17:18 volumio volumio[3362]: info: Preload queue cleared Mar 27 13:17:18 volumio volumio[3362]: info: Adding Item to queue: music-library/USB/T7-4TB/Back to Black [24-96]/01 Rehab.flac Mar 27 13:17:18 volumio volumio[3362]: info: Exploding uri music-library/USB/T7-4TB/Back to Black [24-96]/01 Rehab.flac in service mpd Mar 27 13:17:18 volumio volumio[3362]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 13:17:18 volumio volumio[3362]: TypeError: Cannot read properties of undefined (reading 'then') Mar 27 13:17:18 volumio volumio[3362]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2541:19) Mar 27 13:17:18 volumio volumio[3362]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2339:21) Mar 27 13:17:18 volumio volumio[3362]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) Mar 27 13:17:18 volumio volumio[3362]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Mar 27 13:17:18 volumio volumio[3362]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Mar 27 13:17:18 volumio volumio[3362]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Mar 27 13:17:18 volumio volumio[3362]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:251:25) Mar 27 13:17:18 volumio volumio[3362]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:511:25) Mar 27 13:17:18 volumio volumio[3362]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Mar 27 13:17:18 volumio volumio[3362]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Mar 27 13:17:18 volumio volumio[3362]: at Socket.emit (node:events:514:28) Mar 27 13:17:18 volumio volumio[3362]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 27 13:17:18 volumio volumio[3362]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Mar 27 13:17:18 volumio volumio[3362]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 13:17:19 volumio mpd[3527]: 2026-03-27T13:17:19 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 27 13:17:19 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 27 13:17:19 volumio sudo[3496]: pam_unix(sudo:session): session closed for user root Mar 27 13:17:19 volumio sudo[3484]: pam_unix(sudo:session): session closed for user root Mar 27 13:17:19 volumio volumio5-onboarding[1506]: time=2026-03-27T13:17:19.214+01:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 27 13:17:19 volumio sudo[3599]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-27 13:16' Mar 27 13:17:19 volumio sudo[3599]: 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"