May 29 20:06:00 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:06:03 volumio-alpha wpa_supplicant[2665]: RRM: Ignoring radio measurement request: Not RRM network May 29 20:06:04 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:06:08 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:06:12 volumio-alpha systemd[1]: systemd-timedated.service: Deactivated successfully. May 29 20:06:12 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:06:16 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:06:17 volumio-alpha wpa_supplicant[2665]: RRM: Ignoring radio measurement request: Not RRM network May 29 20:06:18 volumio-alpha volumio[2838]: info: CALLMETHOD: miscellanea my_music updateMusicLibraryBrowseSourcesVisibility [object Object] May 29 20:06:18 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: my_music , updateMusicLibraryBrowseSourcesVisibility May 29 20:06:18 volumio-alpha volumio[2838]: info: CoreCommandRouter::volumioUpdateToBrowseSources May 29 20:06:18 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 20:06:18 volumio-alpha volumio[2838]: Cannot find translation for source YouTube Music May 29 20:06:18 volumio-alpha volumio[2838]: Cannot find translation for source Spotify May 29 20:06:20 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:06:24 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:06:24 volumio-alpha wpa_supplicant[2665]: RRM: Ignoring radio measurement request: Not RRM network May 29 20:06:28 volumio-alpha go-librespot[4419]: time="2025-05-29T20:06:28+01:00" level=trace msg="sent dealer ping" May 29 20:06:28 volumio-alpha go-librespot[4419]: time="2025-05-29T20:06:28+01:00" level=trace msg="received dealer pong" May 29 20:06:28 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:06:31 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 29 20:06:31 volumio-alpha volumio[2838]: info: CURURI: music-library May 29 20:06:31 volumio-alpha volumio[2838]: info: Preload queue cleared May 29 20:06:33 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 29 20:06:33 volumio-alpha volumio[2838]: info: CURURI: music-library/NAS May 29 20:06:33 volumio-alpha volumio[2838]: info: Preload queue cleared May 29 20:06:34 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 29 20:06:34 volumio-alpha volumio[2838]: info: CURURI: music-library/NAS/Music May 29 20:06:34 volumio-alpha volumio[2838]: info: Preload queue cleared May 29 20:06:37 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 29 20:06:37 volumio-alpha volumio[2838]: info: CURURI: music-library/NAS/Music/Playlists May 29 20:06:37 volumio-alpha volumio[2838]: info: Preload queue cleared May 29 20:06:37 volumio-alpha volumio[2838]: info: Preloading song: music-library/NAS/Music/Playlists/Beethoven.m3u May 29 20:06:37 volumio-alpha volumio[2838]: info: Preloading song: music-library/NAS/Music/Playlists/Brass Music.m3u May 29 20:06:37 volumio-alpha volumio[2838]: info: Preloading song: music-library/NAS/Music/Playlists/Christmas Carols.m3u May 29 20:06:37 volumio-alpha volumio[2838]: info: Preloading song: music-library/NAS/Music/Playlists/Easy listening.m3u May 29 20:06:37 volumio-alpha volumio[2838]: info: Preloading song: music-library/NAS/Music/Playlists/Golden Age of Light Music.m3u May 29 20:06:37 volumio-alpha volumio[2838]: info: Preloading song: music-library/NAS/Music/Playlists/Leroy Anderson.m3u May 29 20:06:37 volumio-alpha volumio[2838]: info: Preloading song: music-library/NAS/Music/Playlists/Light British Music.m3u May 29 20:06:37 volumio-alpha volumio[2838]: info: Preloading song: music-library/NAS/Music/Playlists/Medieval Choral.m3u May 29 20:06:37 volumio-alpha volumio[2838]: info: Preloading song: music-library/NAS/Music/Playlists/Round the Horne.m3u May 29 20:06:37 volumio-alpha volumio[2838]: info: Preloading song: music-library/NAS/Music/Playlists/Secret Garden.m3u May 29 20:06:37 volumio-alpha volumio[2838]: info: Preloading song: music-library/NAS/Music/Playlists/Sing Something Simple.m3u May 29 20:06:37 volumio-alpha volumio[2838]: info: Preloading song: music-library/NAS/Music/Playlists/This Sceptred Isle.m3u May 29 20:06:37 volumio-alpha volumio[2838]: info: Preloading song: music-library/NAS/Music/Playlists/Vaughan Williams.m3u May 29 20:06:37 volumio-alpha volumio[2838]: info: Exploding uri music-library/NAS/Music/Playlists/Beethoven.m3u in service mpd May 29 20:06:37 volumio-alpha volumio[2838]: info: Exploding uri music-library/NAS/Music/Playlists/Brass Music.m3u in service mpd May 29 20:06:37 volumio-alpha volumio[2838]: info: Exploding uri music-library/NAS/Music/Playlists/Christmas Carols.m3u in service mpd May 29 20:06:37 volumio-alpha volumio[2838]: info: Exploding uri music-library/NAS/Music/Playlists/Easy listening.m3u in service mpd May 29 20:06:38 volumio-alpha volumio[2838]: info: Exploding uri music-library/NAS/Music/Playlists/Golden Age of Light Music.m3u in service mpd May 29 20:06:38 volumio-alpha volumio[2838]: info: Exploding uri music-library/NAS/Music/Playlists/Leroy Anderson.m3u in service mpd May 29 20:06:38 volumio-alpha volumio[2838]: info: Exploding uri music-library/NAS/Music/Playlists/Light British Music.m3u in service mpd May 29 20:06:38 volumio-alpha volumio[2838]: info: Exploding uri music-library/NAS/Music/Playlists/Medieval Choral.m3u in service mpd May 29 20:06:38 volumio-alpha volumio[2838]: info: Exploding uri music-library/NAS/Music/Playlists/Round the Horne.m3u in service mpd May 29 20:06:38 volumio-alpha volumio[2838]: info: Exploding uri music-library/NAS/Music/Playlists/Secret Garden.m3u in service mpd May 29 20:06:38 volumio-alpha volumio[2838]: info: Exploding uri music-library/NAS/Music/Playlists/Sing Something Simple.m3u in service mpd May 29 20:06:38 volumio-alpha volumio[2838]: info: Exploding uri music-library/NAS/Music/Playlists/This Sceptred Isle.m3u in service mpd May 29 20:06:38 volumio-alpha volumio[2838]: info: Exploding uri music-library/NAS/Music/Playlists/Vaughan Williams.m3u in service mpd May 29 20:06:39 volumio-alpha wpa_supplicant[2665]: RRM: Ignoring radio measurement request: Not RRM network May 29 20:06:43 volumio-alpha volumio[2838]: info: Preload queue cleared May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreCommandRouter::volumioReplaceandPlayItems May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreStateMachine::ClearQueue May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreStateMachine::stop May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreStateMachine::stPlaybackTimer May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreStateMachine::updateTrackBlock May 29 20:06:43 volumio-alpha volumio[2838]: info: CorePlayQueue::getTrackBlock May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreStateMachine::pushState May 29 20:06:43 volumio-alpha volumio[2838]: info: CorePlayQueue::getTrack 0 May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreCommandRouter::volumioPushState May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreStateMachine::serviceStop May 29 20:06:43 volumio-alpha volumio[2838]: info: CorePlayQueue::getTrack 0 May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreCommandRouter::serviceStop May 29 20:06:43 volumio-alpha volumio[2838]: info: [1748545603683] ControllerWebradio::stop May 29 20:06:43 volumio-alpha volumio[2838]: verbose: ControllerMpd::sendMpdCommand stop May 29 20:06:43 volumio-alpha volumio[2838]: info: CorePlayQueue::clearPlayQueue May 29 20:06:43 volumio-alpha volumio[2838]: info: CorePlayQueue::saveQueue May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreCommandRouter::volumioPushQueue May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreStateMachine::addQueueItems May 29 20:06:43 volumio-alpha volumio[2838]: info: CorePlayQueue::addQueueItems May 29 20:06:43 volumio-alpha volumio[2838]: info: Preload queue cleared May 29 20:06:43 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Beethoven.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Beethoven.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Brass Music.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Brass Music.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Christmas Carols.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Christmas Carols.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Easy listening.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Easy listening.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Golden Age of Light Music.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Golden Age of Light Music.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Leroy Anderson.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Leroy Anderson.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Light British Music.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Light British Music.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Medieval Choral.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Medieval Choral.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Round the Horne.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Round the Horne.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Secret Garden.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Secret Garden.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Sing Something Simple.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Sing Something Simple.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/This Sceptred Isle.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/This Sceptred Isle.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Vaughan Williams.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Vaughan Williams.m3u May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreCommandRouter::volumioPushQueue May 29 20:06:43 volumio-alpha volumio[2838]: info: CorePlayQueue::saveQueue May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreStateMachine::updateTrackBlock May 29 20:06:43 volumio-alpha volumio[2838]: info: CorePlayQueue::getTrackBlock May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreCommandRouter::volumioPlay May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreStateMachine::play index 12 May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreStateMachine::stop May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreStateMachine::play index undefined May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:06:43 volumio-alpha volumio[2838]: info: CorePlayQueue::getTrack 12 May 29 20:06:43 volumio-alpha volumio[2838]: info: May 29 20:06:43 volumio-alpha volumio[2838]: ---------------------------- MPD announces state update: player May 29 20:06:43 volumio-alpha volumio[2838]: info: sendMpdCommand stop took 11 milliseconds May 29 20:06:43 volumio-alpha volumio[2838]: info: ControllerMpd::getState May 29 20:06:43 volumio-alpha volumio[2838]: verbose: ControllerMpd::sendMpdCommand status May 29 20:06:43 volumio-alpha volumio[2838]: info: sendMpdCommand status took 1 milliseconds May 29 20:06:43 volumio-alpha volumio[2838]: verbose: ControllerMpd::parseState May 29 20:06:43 volumio-alpha volumio[2838]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 20:06:43 volumio-alpha volumio[2838]: info: sendMpdCommand playlistinfo took 1 milliseconds May 29 20:06:43 volumio-alpha volumio[2838]: verbose: ControllerMpd::parseTrackInfo May 29 20:06:43 volumio-alpha volumio[2838]: info: ControllerMpd::pushState May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreCommandRouter::servicePushState May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreStateMachine::pushState May 29 20:06:43 volumio-alpha volumio[2838]: info: CorePlayQueue::getTrack 0 May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreCommandRouter::volumioPushState May 29 20:06:43 volumio-alpha volumio[2838]: info: CorePlayQueue::getTrack 0 May 29 20:06:43 volumio-alpha volumio[2838]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_fourfm-audio=320000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_55057080/live/ww/bbc_radio_fourfm/bbc_radio_fourfm.isml/bbc_radio_fourfm-audio=320000.norewind.m3u8","trackType":"m3u8"} May 29 20:06:43 volumio-alpha volumio[2838]: verbose: CURRENT POSITION 0 May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreStateMachine::syncState stateService stop May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreStateMachine::syncState currentStatus stop May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreStateMachine::pushState May 29 20:06:43 volumio-alpha volumio[2838]: info: CorePlayQueue::getTrack 0 May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreCommandRouter::volumioPushState May 29 20:06:43 volumio-alpha volumio[2838]: info: No code May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreStateMachine::pushState May 29 20:06:43 volumio-alpha volumio[2838]: info: CorePlayQueue::getTrack 0 May 29 20:06:43 volumio-alpha volumio[2838]: info: CoreCommandRouter::volumioPushState May 29 20:06:43 volumio-alpha volumio[2838]: info: ------------------------------ 13ms May 29 20:06:45 volumio-alpha wpa_supplicant[2665]: RRM: Ignoring radio measurement request: Not RRM network May 29 20:06:50 volumio-alpha volumio[2838]: info: Preload queue cleared May 29 20:06:50 volumio-alpha volumio[2838]: info: CoreCommandRouter::volumioReplaceandPlayItems May 29 20:06:50 volumio-alpha volumio[2838]: info: CoreStateMachine::ClearQueue May 29 20:06:50 volumio-alpha volumio[2838]: info: CoreStateMachine::stop May 29 20:06:50 volumio-alpha volumio[2838]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:06:50 volumio-alpha volumio[2838]: info: CorePlayQueue::clearPlayQueue May 29 20:06:50 volumio-alpha volumio[2838]: info: CorePlayQueue::saveQueue May 29 20:06:50 volumio-alpha volumio[2838]: info: CoreCommandRouter::volumioPushQueue May 29 20:06:50 volumio-alpha volumio[2838]: info: CoreStateMachine::addQueueItems May 29 20:06:50 volumio-alpha volumio[2838]: info: CorePlayQueue::addQueueItems May 29 20:06:50 volumio-alpha volumio[2838]: info: Preload queue cleared May 29 20:06:50 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Beethoven.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Beethoven.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Brass Music.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Brass Music.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Christmas Carols.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Christmas Carols.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Easy listening.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Easy listening.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Golden Age of Light Music.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Golden Age of Light Music.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Leroy Anderson.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Leroy Anderson.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Light British Music.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Light British Music.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Medieval Choral.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Medieval Choral.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Round the Horne.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Round the Horne.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Secret Garden.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Secret Garden.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Sing Something Simple.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Sing Something Simple.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/This Sceptred Isle.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/This Sceptred Isle.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Vaughan Williams.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Vaughan Williams.m3u May 29 20:06:50 volumio-alpha volumio[2838]: info: CoreCommandRouter::volumioPushQueue May 29 20:06:50 volumio-alpha volumio[2838]: info: CorePlayQueue::saveQueue May 29 20:06:50 volumio-alpha volumio[2838]: info: CoreStateMachine::updateTrackBlock May 29 20:06:50 volumio-alpha volumio[2838]: info: CorePlayQueue::getTrackBlock May 29 20:06:50 volumio-alpha volumio[2838]: info: CoreCommandRouter::volumioPlay May 29 20:06:50 volumio-alpha volumio[2838]: info: CoreStateMachine::play index 12 May 29 20:06:50 volumio-alpha volumio[2838]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:06:50 volumio-alpha volumio[2838]: info: CoreStateMachine::stop May 29 20:06:50 volumio-alpha volumio[2838]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:06:50 volumio-alpha volumio[2838]: info: CoreStateMachine::play index undefined May 29 20:06:50 volumio-alpha volumio[2838]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:06:50 volumio-alpha volumio[2838]: info: CorePlayQueue::getTrack 12 May 29 20:06:53 volumio-alpha volumio[2838]: info: Preload queue cleared May 29 20:06:53 volumio-alpha volumio[2838]: info: CoreCommandRouter::volumioReplaceandPlayItems May 29 20:06:53 volumio-alpha volumio[2838]: info: CoreStateMachine::ClearQueue May 29 20:06:53 volumio-alpha volumio[2838]: info: CoreStateMachine::stop May 29 20:06:53 volumio-alpha volumio[2838]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:06:53 volumio-alpha volumio[2838]: info: CorePlayQueue::clearPlayQueue May 29 20:06:53 volumio-alpha volumio[2838]: info: CorePlayQueue::saveQueue May 29 20:06:53 volumio-alpha volumio[2838]: info: CoreCommandRouter::volumioPushQueue May 29 20:06:53 volumio-alpha volumio[2838]: info: CoreStateMachine::addQueueItems May 29 20:06:53 volumio-alpha volumio[2838]: info: CorePlayQueue::addQueueItems May 29 20:06:53 volumio-alpha volumio[2838]: info: Preload queue cleared May 29 20:06:53 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Beethoven.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Beethoven.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Brass Music.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Brass Music.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Christmas Carols.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Christmas Carols.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Easy listening.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Easy listening.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Golden Age of Light Music.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Golden Age of Light Music.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Leroy Anderson.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Leroy Anderson.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Light British Music.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Light British Music.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Medieval Choral.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Medieval Choral.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Round the Horne.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Round the Horne.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Secret Garden.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Secret Garden.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Sing Something Simple.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Sing Something Simple.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/This Sceptred Isle.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/This Sceptred Isle.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Adding Item to queue: music-library/NAS/Music/Playlists/Vaughan Williams.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: Using cached record of: music-library/NAS/Music/Playlists/Vaughan Williams.m3u May 29 20:06:53 volumio-alpha volumio[2838]: info: CoreCommandRouter::volumioPushQueue May 29 20:06:53 volumio-alpha volumio[2838]: info: CorePlayQueue::saveQueue May 29 20:06:53 volumio-alpha volumio[2838]: info: CoreStateMachine::updateTrackBlock May 29 20:06:53 volumio-alpha volumio[2838]: info: CorePlayQueue::getTrackBlock May 29 20:06:53 volumio-alpha volumio[2838]: info: CoreCommandRouter::volumioPlay May 29 20:06:53 volumio-alpha volumio[2838]: info: CoreStateMachine::play index 12 May 29 20:06:53 volumio-alpha volumio[2838]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:06:53 volumio-alpha volumio[2838]: info: CoreStateMachine::stop May 29 20:06:53 volumio-alpha volumio[2838]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:06:53 volumio-alpha volumio[2838]: info: CoreStateMachine::play index undefined May 29 20:06:53 volumio-alpha volumio[2838]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:06:53 volumio-alpha volumio[2838]: info: CorePlayQueue::getTrack 12 May 29 20:06:58 volumio-alpha go-librespot[4419]: time="2025-05-29T20:06:58+01:00" level=trace msg="sent dealer ping" May 29 20:06:58 volumio-alpha go-librespot[4419]: time="2025-05-29T20:06:58+01:00" level=trace msg="received dealer pong" May 29 20:07:01 volumio-alpha go-librespot[4419]: time="2025-05-29T20:07:01+01:00" level=trace msg="received accesspoint ping" May 29 20:07:01 volumio-alpha go-librespot[4419]: time="2025-05-29T20:07:01+01:00" level=trace msg="received accesspoint pong ack" May 29 20:07:02 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 29 20:07:02 volumio-alpha volumio[2838]: info: Preload queue cleared May 29 20:07:03 volumio-alpha nmbd[2428]: [2025/05/29 20:07:03.871534, 0] ../../source3/libsmb/nmblib.c:923(send_udp) May 29 20:07:03 volumio-alpha nmbd[2428]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable May 29 20:07:05 volumio-alpha wpa_supplicant[2665]: RRM: Ignoring radio measurement request: Not RRM network May 29 20:07:07 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 29 20:07:07 volumio-alpha volumio[2838]: info: Preload queue cleared May 29 20:07:07 volumio-alpha systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 29 20:07:07 volumio-alpha dbus-daemon[875]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.205' (uid=0 pid=15874 comm="timedatectl show --property=NTPSynchronized --valu") May 29 20:07:07 volumio-alpha systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 29 20:07:07 volumio-alpha dbus-daemon[875]: [system] Successfully activated service 'org.freedesktop.timedate1' May 29 20:07:07 volumio-alpha systemd[1]: Started systemd-timedated.service - Time & Date Service. May 29 20:07:07 volumio-alpha setdatetime-helper.sh[15873]: Time is already synchronized. May 29 20:07:07 volumio-alpha systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 29 20:07:07 volumio-alpha systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 29 20:07:09 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 29 20:07:12 volumio-alpha wpa_supplicant[2665]: RRM: Ignoring radio measurement request: Not RRM network May 29 20:07:23 volumio-alpha wpa_supplicant[2665]: RRM: Ignoring radio measurement request: Not RRM network May 29 20:07:26 volumio-alpha volumio[2838]: info: Preload queue cleared May 29 20:07:28 volumio-alpha go-librespot[4419]: time="2025-05-29T20:07:28+01:00" level=trace msg="sent dealer ping" May 29 20:07:28 volumio-alpha go-librespot[4419]: time="2025-05-29T20:07:28+01:00" level=trace msg="received dealer pong" May 29 20:07:31 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 29 20:07:31 volumio-alpha volumio[2838]: info: Preload queue cleared May 29 20:07:31 volumio-alpha volumio[2838]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 29 20:07:31 volumio-alpha volumio[2838]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 29 20:07:31 volumio-alpha volumio[2838]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 29 20:07:31 volumio-alpha volumio[2838]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 29 20:07:31 volumio-alpha volumio[2838]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 29 20:07:31 volumio-alpha volumio[2838]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 29 20:07:31 volumio-alpha volumio[2838]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 29 20:07:32 volumio-alpha volumio[2838]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 29 20:07:36 volumio-alpha volumio[2838]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 29 20:07:36 volumio-alpha volumio[2838]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 20:07:36 volumio-alpha volumio[2838]: TypeError: Cannot read properties of undefined (reading 'length') May 29 20:07:36 volumio-alpha volumio[2838]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 29 20:07:36 volumio-alpha volumio[2838]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 29 20:07:36 volumio-alpha volumio[2838]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 29 20:07:36 volumio-alpha volumio[2838]: at Parser.emit (node:events:514:28) May 29 20:07:36 volumio-alpha volumio[2838]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 29 20:07:36 volumio-alpha volumio[2838]: at emit (/volumio/node_modules/sax/lib/sax.js:625:35) May 29 20:07:36 volumio-alpha volumio[2838]: at emitNode (/volumio/node_modules/sax/lib/sax.js:630:5) May 29 20:07:36 volumio-alpha volumio[2838]: at closeTag (/volumio/node_modules/sax/lib/sax.js:890:7) May 29 20:07:36 volumio-alpha volumio[2838]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1437:13) May 29 20:07:36 volumio-alpha volumio[2838]: at exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 29 20:07:36 volumio-alpha volumio[2838]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 29 20:07:36 volumio-alpha volumio[2838]: at exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 29 20:07:36 volumio-alpha volumio[2838]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 29 20:07:36 volumio-alpha volumio[2838]: at IncomingMessage.emit (node:events:526:35) May 29 20:07:36 volumio-alpha volumio[2838]: at endReadableNT (node:internal/streams/readable:1376:12) May 29 20:07:36 volumio-alpha volumio[2838]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) May 29 20:07:36 volumio-alpha volumio[2838]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 20:07:36 volumio-alpha sudo[15935]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-29 20:06' May 29 20:07:36 volumio-alpha sudo[15935]: 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="1da895bfdbb6eb90d965df32a25a3568df7c84ed" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu May 29 13:12:59 UTC 2025" VOLUMIO_VERSION="4.004" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2df63d74196e499fa1e34fb765df1cca"