May 31 09:13:01 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 09:13:01 volumio volumio[983]: info: CURURI: music-library May 31 09:13:01 volumio volumio[983]: info: Preload queue cleared May 31 09:13:04 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 09:13:04 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 09:13:04 volumio volumio[983]: info: Discovery: Getting this device information May 31 09:13:04 volumio volumio[983]: info: CoreCommandRouter::volumioGetState May 31 09:13:04 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 09:13:04 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 09:13:04 volumio volumio[983]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 31 09:13:04 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 31 09:13:04 volumio volumio[983]: info: Received Get System Version May 31 09:13:04 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 09:13:04 volumio volumio[983]: info: Received Get System Info May 31 09:13:04 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 09:13:04 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 09:13:04 volumio volumio[983]: info: Discovery: Getting this device information May 31 09:13:04 volumio volumio[983]: info: CoreCommandRouter::volumioGetState May 31 09:13:04 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 09:13:05 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 09:13:05 volumio volumio[983]: info: CURURI: music-library/INTERNAL May 31 09:13:05 volumio volumio[983]: info: Preload queue cleared May 31 09:13:05 volumio volumio[983]: info: Preloading song: music-library/INTERNAL/LFR_20250221220000_20250222000000.aac May 31 09:13:05 volumio volumio[983]: info: Preloading song: music-library/INTERNAL/LFR_20250228153000_20250228171000.aac May 31 09:13:05 volumio volumio[983]: info: Preloading song: music-library/INTERNAL/LFR_20250307153000_20250307171000.aac May 31 09:13:05 volumio volumio[983]: info: Preloading song: music-library/INTERNAL/LFR_20250314153000_20250314171000.aac May 31 09:13:05 volumio volumio[983]: info: Preloading song: music-library/INTERNAL/LFR_20250314220000_20250315000000.aac May 31 09:13:05 volumio volumio[983]: info: Preloading song: music-library/INTERNAL/LFR_20250321153000_20250321171000.aac May 31 09:13:05 volumio volumio[983]: info: Preloading song: music-library/INTERNAL/LFR_20250328153000_20250328171000.aac May 31 09:13:05 volumio volumio[983]: info: Preloading song: music-library/INTERNAL/LFR_20250411153000_20250411171000.aac May 31 09:13:05 volumio volumio[983]: info: Preloading song: music-library/INTERNAL/LFR_20250411220000_20250412000000.aac May 31 09:13:05 volumio volumio[983]: info: Preloading song: music-library/INTERNAL/LFR_20250418153000_20250418171000.aac May 31 09:13:05 volumio volumio[983]: info: Preloading song: music-library/INTERNAL/LFR_20250425153000_20250425171000.aac May 31 09:13:05 volumio volumio[983]: info: Preloading song: music-library/INTERNAL/LFR_20250502153000_20250502171000.aac May 31 09:13:05 volumio volumio[983]: info: Preloading song: music-library/INTERNAL/LFR_20250509153000_20250509171000.aac May 31 09:13:05 volumio volumio[983]: info: Preloading song: music-library/INTERNAL/LFR_20250516153000_20250516171000.aac May 31 09:13:05 volumio volumio[983]: info: Preloading song: music-library/INTERNAL/LFR_20250516220000_20250517000000.aac May 31 09:13:05 volumio volumio[983]: info: Preloading song: music-library/INTERNAL/LFR_20250523153000_20250523171000.aac May 31 09:13:05 volumio volumio[983]: info: Preloading song: music-library/INTERNAL/LFR_20250530153000_20250530171000.aac May 31 09:13:05 volumio volumio[983]: info: Exploding uri music-library/INTERNAL/LFR_20250221220000_20250222000000.aac in service mpd May 31 09:13:05 volumio volumio[983]: info: ALBUMART /albumart?cacheid=479&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FLFR_20250221220000_20250222000000.aac&metadata=false May 31 09:13:05 volumio volumio[983]: info: URI /mnt/INTERNAL/LFR_20250221220000_20250222000000.aac May 31 09:13:05 volumio volumio[983]: info: Exploding uri music-library/INTERNAL/LFR_20250228153000_20250228171000.aac in service mpd May 31 09:13:05 volumio volumio[983]: info: ALBUMART /albumart?cacheid=479&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FLFR_20250228153000_20250228171000.aac&metadata=false May 31 09:13:05 volumio volumio[983]: info: URI /mnt/INTERNAL/LFR_20250228153000_20250228171000.aac May 31 09:13:05 volumio volumio[983]: info: Exploding uri music-library/INTERNAL/LFR_20250307153000_20250307171000.aac in service mpd May 31 09:13:05 volumio volumio[983]: info: ALBUMART /albumart?cacheid=479&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FLFR_20250307153000_20250307171000.aac&metadata=false May 31 09:13:05 volumio volumio[983]: info: URI /mnt/INTERNAL/LFR_20250307153000_20250307171000.aac May 31 09:13:05 volumio volumio[983]: info: Exploding uri music-library/INTERNAL/LFR_20250314153000_20250314171000.aac in service mpd May 31 09:13:05 volumio volumio[983]: info: ALBUMART /albumart?cacheid=479&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FLFR_20250314153000_20250314171000.aac&metadata=false May 31 09:13:05 volumio volumio[983]: info: URI /mnt/INTERNAL/LFR_20250314153000_20250314171000.aac May 31 09:13:05 volumio volumio[983]: info: Exploding uri music-library/INTERNAL/LFR_20250314220000_20250315000000.aac in service mpd May 31 09:13:05 volumio volumio[983]: info: ALBUMART /albumart?cacheid=479&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FLFR_20250314220000_20250315000000.aac&metadata=false May 31 09:13:05 volumio volumio[983]: info: URI /mnt/INTERNAL/LFR_20250314220000_20250315000000.aac May 31 09:13:05 volumio volumio[983]: info: Exploding uri music-library/INTERNAL/LFR_20250321153000_20250321171000.aac in service mpd May 31 09:13:05 volumio volumio[983]: info: ALBUMART /albumart?cacheid=479&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FLFR_20250321153000_20250321171000.aac&metadata=false May 31 09:13:05 volumio volumio[983]: info: URI /mnt/INTERNAL/LFR_20250321153000_20250321171000.aac May 31 09:13:05 volumio volumio[983]: info: Exploding uri music-library/INTERNAL/LFR_20250328153000_20250328171000.aac in service mpd May 31 09:13:05 volumio volumio[983]: info: ALBUMART /albumart?cacheid=479&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FLFR_20250328153000_20250328171000.aac&metadata=false May 31 09:13:05 volumio volumio[983]: info: URI /mnt/INTERNAL/LFR_20250328153000_20250328171000.aac May 31 09:13:05 volumio volumio[983]: info: Exploding uri music-library/INTERNAL/LFR_20250411153000_20250411171000.aac in service mpd May 31 09:13:05 volumio volumio[983]: info: ALBUMART /albumart?cacheid=479&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FLFR_20250411153000_20250411171000.aac&metadata=false May 31 09:13:05 volumio volumio[983]: info: URI /mnt/INTERNAL/LFR_20250411153000_20250411171000.aac May 31 09:13:05 volumio volumio[983]: info: Exploding uri music-library/INTERNAL/LFR_20250411220000_20250412000000.aac in service mpd May 31 09:13:05 volumio volumio[983]: info: ALBUMART /albumart?cacheid=479&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FLFR_20250411220000_20250412000000.aac&metadata=false May 31 09:13:05 volumio volumio[983]: info: URI /mnt/INTERNAL/LFR_20250411220000_20250412000000.aac May 31 09:13:05 volumio volumio[983]: info: Exploding uri music-library/INTERNAL/LFR_20250418153000_20250418171000.aac in service mpd May 31 09:13:05 volumio volumio[983]: info: ALBUMART /albumart?cacheid=479&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FLFR_20250418153000_20250418171000.aac&metadata=false May 31 09:13:05 volumio volumio[983]: info: URI /mnt/INTERNAL/LFR_20250418153000_20250418171000.aac May 31 09:13:05 volumio volumio[983]: info: Exploding uri music-library/INTERNAL/LFR_20250425153000_20250425171000.aac in service mpd May 31 09:13:05 volumio volumio[983]: info: ALBUMART /albumart?cacheid=479&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FLFR_20250425153000_20250425171000.aac&metadata=false May 31 09:13:05 volumio volumio[983]: info: URI /mnt/INTERNAL/LFR_20250425153000_20250425171000.aac May 31 09:13:05 volumio volumio[983]: info: Exploding uri music-library/INTERNAL/LFR_20250502153000_20250502171000.aac in service mpd May 31 09:13:05 volumio volumio[983]: info: ALBUMART /albumart?cacheid=479&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FLFR_20250502153000_20250502171000.aac&metadata=false May 31 09:13:05 volumio volumio[983]: info: URI /mnt/INTERNAL/LFR_20250502153000_20250502171000.aac May 31 09:13:05 volumio volumio[983]: info: Exploding uri music-library/INTERNAL/LFR_20250509153000_20250509171000.aac in service mpd May 31 09:13:05 volumio volumio[983]: info: ALBUMART /albumart?cacheid=479&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FLFR_20250509153000_20250509171000.aac&metadata=false May 31 09:13:05 volumio volumio[983]: info: URI /mnt/INTERNAL/LFR_20250509153000_20250509171000.aac May 31 09:13:05 volumio volumio[983]: info: Exploding uri music-library/INTERNAL/LFR_20250516153000_20250516171000.aac in service mpd May 31 09:13:05 volumio volumio[983]: info: ALBUMART /albumart?cacheid=479&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FLFR_20250516153000_20250516171000.aac&metadata=false May 31 09:13:05 volumio volumio[983]: info: URI /mnt/INTERNAL/LFR_20250516153000_20250516171000.aac May 31 09:13:05 volumio volumio[983]: info: Exploding uri music-library/INTERNAL/LFR_20250516220000_20250517000000.aac in service mpd May 31 09:13:05 volumio volumio[983]: info: ALBUMART /albumart?cacheid=479&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FLFR_20250516220000_20250517000000.aac&metadata=false May 31 09:13:05 volumio volumio[983]: info: URI /mnt/INTERNAL/LFR_20250516220000_20250517000000.aac May 31 09:13:05 volumio volumio[983]: info: Exploding uri music-library/INTERNAL/LFR_20250523153000_20250523171000.aac in service mpd May 31 09:13:05 volumio volumio[983]: info: ALBUMART /albumart?cacheid=479&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FLFR_20250523153000_20250523171000.aac&metadata=false May 31 09:13:05 volumio volumio[983]: info: URI /mnt/INTERNAL/LFR_20250523153000_20250523171000.aac May 31 09:13:05 volumio volumio[983]: info: Exploding uri music-library/INTERNAL/LFR_20250530153000_20250530171000.aac in service mpd May 31 09:13:05 volumio volumio[983]: info: ALBUMART /albumart?cacheid=479&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FLFR_20250530153000_20250530171000.aac&metadata=false May 31 09:13:05 volumio volumio[983]: info: URI /mnt/INTERNAL/LFR_20250530153000_20250530171000.aac May 31 09:13:08 volumio volumio[983]: info: Preload queue cleared May 31 09:13:08 volumio volumio[983]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::ClearQueue May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::stop May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::stPlaybackTimer May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::updateTrackBlock May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::getTrackBlock May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::pushState May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::getTrack 0 May 31 09:13:08 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 09:13:08 volumio volumio[983]: info: CoreCommandRouter::volumioPushState May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::serviceStop May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::getTrack 0 May 31 09:13:08 volumio volumio[983]: info: CoreCommandRouter::serviceStop May 31 09:13:08 volumio volumio[983]: info: [1748650388772] ControllerWebradio::stop May 31 09:13:08 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand stop May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::clearPlayQueue May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::saveQueue May 31 09:13:08 volumio volumio[983]: info: CoreCommandRouter::volumioPushQueue May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::addQueueItems May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::addQueueItems May 31 09:13:08 volumio volumio[983]: info: Preload queue cleared May 31 09:13:08 volumio volumio[983]: info: Adding Item to queue: music-library/INTERNAL/LFR_20250221220000_20250222000000.aac May 31 09:13:08 volumio volumio[983]: info: Using cached record of: music-library/INTERNAL/LFR_20250221220000_20250222000000.aac May 31 09:13:08 volumio volumio[983]: info: Adding Item to queue: music-library/INTERNAL/LFR_20250228153000_20250228171000.aac May 31 09:13:08 volumio volumio[983]: info: Using cached record of: music-library/INTERNAL/LFR_20250228153000_20250228171000.aac May 31 09:13:08 volumio volumio[983]: info: Adding Item to queue: music-library/INTERNAL/LFR_20250307153000_20250307171000.aac May 31 09:13:08 volumio volumio[983]: info: Using cached record of: music-library/INTERNAL/LFR_20250307153000_20250307171000.aac May 31 09:13:08 volumio volumio[983]: info: Adding Item to queue: music-library/INTERNAL/LFR_20250314153000_20250314171000.aac May 31 09:13:08 volumio volumio[983]: info: Using cached record of: music-library/INTERNAL/LFR_20250314153000_20250314171000.aac May 31 09:13:08 volumio volumio[983]: info: Adding Item to queue: music-library/INTERNAL/LFR_20250314220000_20250315000000.aac May 31 09:13:08 volumio volumio[983]: info: Using cached record of: music-library/INTERNAL/LFR_20250314220000_20250315000000.aac May 31 09:13:08 volumio volumio[983]: info: Adding Item to queue: music-library/INTERNAL/LFR_20250321153000_20250321171000.aac May 31 09:13:08 volumio volumio[983]: info: Using cached record of: music-library/INTERNAL/LFR_20250321153000_20250321171000.aac May 31 09:13:08 volumio volumio[983]: info: CoreCommandRouter::volumioPushQueue May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::saveQueue May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::updateTrackBlock May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::getTrackBlock May 31 09:13:08 volumio volumio[983]: info: CoreCommandRouter::volumioPlay May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::play index 5 May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::addQueueItems May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::addQueueItems May 31 09:13:08 volumio volumio[983]: info: Preload queue cleared May 31 09:13:08 volumio volumio[983]: info: Adding Item to queue: music-library/INTERNAL/LFR_20250328153000_20250328171000.aac May 31 09:13:08 volumio volumio[983]: info: Using cached record of: music-library/INTERNAL/LFR_20250328153000_20250328171000.aac May 31 09:13:08 volumio volumio[983]: info: Adding Item to queue: music-library/INTERNAL/LFR_20250411153000_20250411171000.aac May 31 09:13:08 volumio volumio[983]: info: Using cached record of: music-library/INTERNAL/LFR_20250411153000_20250411171000.aac May 31 09:13:08 volumio volumio[983]: info: Adding Item to queue: music-library/INTERNAL/LFR_20250411220000_20250412000000.aac May 31 09:13:08 volumio volumio[983]: info: Using cached record of: music-library/INTERNAL/LFR_20250411220000_20250412000000.aac May 31 09:13:08 volumio volumio[983]: info: Adding Item to queue: music-library/INTERNAL/LFR_20250418153000_20250418171000.aac May 31 09:13:08 volumio volumio[983]: info: Using cached record of: music-library/INTERNAL/LFR_20250418153000_20250418171000.aac May 31 09:13:08 volumio volumio[983]: info: Adding Item to queue: music-library/INTERNAL/LFR_20250425153000_20250425171000.aac May 31 09:13:08 volumio volumio[983]: info: Using cached record of: music-library/INTERNAL/LFR_20250425153000_20250425171000.aac May 31 09:13:08 volumio volumio[983]: info: Adding Item to queue: music-library/INTERNAL/LFR_20250502153000_20250502171000.aac May 31 09:13:08 volumio volumio[983]: info: Using cached record of: music-library/INTERNAL/LFR_20250502153000_20250502171000.aac May 31 09:13:08 volumio volumio[983]: info: Adding Item to queue: music-library/INTERNAL/LFR_20250509153000_20250509171000.aac May 31 09:13:08 volumio volumio[983]: info: Using cached record of: music-library/INTERNAL/LFR_20250509153000_20250509171000.aac May 31 09:13:08 volumio volumio[983]: info: Adding Item to queue: music-library/INTERNAL/LFR_20250516153000_20250516171000.aac May 31 09:13:08 volumio volumio[983]: info: Using cached record of: music-library/INTERNAL/LFR_20250516153000_20250516171000.aac May 31 09:13:08 volumio volumio[983]: info: Adding Item to queue: music-library/INTERNAL/LFR_20250516220000_20250517000000.aac May 31 09:13:08 volumio volumio[983]: info: Using cached record of: music-library/INTERNAL/LFR_20250516220000_20250517000000.aac May 31 09:13:08 volumio volumio[983]: info: Adding Item to queue: music-library/INTERNAL/LFR_20250523153000_20250523171000.aac May 31 09:13:08 volumio volumio[983]: info: Using cached record of: music-library/INTERNAL/LFR_20250523153000_20250523171000.aac May 31 09:13:08 volumio volumio[983]: info: Adding Item to queue: music-library/INTERNAL/LFR_20250530153000_20250530171000.aac May 31 09:13:08 volumio volumio[983]: info: Using cached record of: music-library/INTERNAL/LFR_20250530153000_20250530171000.aac May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::stop May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 09:13:08 volumio volumio[983]: info: CoreCommandRouter::volumioPushQueue May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::saveQueue May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::play index undefined May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::updateTrackBlock May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::getTrackBlock May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::startPlaybackTimer May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:08 volumio volumio[983]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/LFR_20250321153000_20250321171000.aac May 31 09:13:08 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand stop May 31 09:13:08 volumio volumio[983]: info: May 31 09:13:08 volumio volumio[983]: ---------------------------- MPD announces state update: player May 31 09:13:08 volumio volumio[983]: info: sendMpdCommand stop took 77 milliseconds May 31 09:13:08 volumio volumio[983]: info: ControllerMpd::getState May 31 09:13:08 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand status May 31 09:13:08 volumio volumio[983]: info: sendMpdCommand stop took 23 milliseconds May 31 09:13:08 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand clear May 31 09:13:08 volumio volumio[983]: info: May 31 09:13:08 volumio volumio[983]: ---------------------------- MPD announces state update: player May 31 09:13:08 volumio volumio[983]: info: ControllerMpd::getState May 31 09:13:08 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand status May 31 09:13:08 volumio volumio[983]: info: May 31 09:13:08 volumio volumio[983]: ---------------------------- MPD announces state update: player May 31 09:13:08 volumio volumio[983]: info: ControllerMpd::getState May 31 09:13:08 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand status May 31 09:13:08 volumio volumio[983]: info: May 31 09:13:08 volumio volumio[983]: ---------------------------- MPD announces system playlist update May 31 09:13:08 volumio volumio[983]: info: Ignoring MPD Status Update May 31 09:13:08 volumio volumio[983]: info: sendMpdCommand status took 11 milliseconds May 31 09:13:08 volumio volumio[983]: info: sendMpdCommand clear took 10 milliseconds May 31 09:13:08 volumio volumio[983]: info: sendMpdCommand status took 9 milliseconds May 31 09:13:08 volumio volumio[983]: info: sendMpdCommand status took 8 milliseconds May 31 09:13:08 volumio volumio[983]: verbose: ControllerMpd::parseState May 31 09:13:08 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 09:13:08 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/LFR_20250321153000_20250321171000.aac" May 31 09:13:08 volumio volumio[983]: verbose: ControllerMpd::parseState May 31 09:13:08 volumio volumio[983]: verbose: ControllerMpd::parseState May 31 09:13:08 volumio volumio[983]: info: ControllerMpd::pushState May 31 09:13:08 volumio volumio[983]: info: CoreCommandRouter::servicePushState May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::pushState May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:08 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 09:13:08 volumio volumio[983]: info: CoreCommandRouter::volumioPushState May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:08 volumio volumio[983]: 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} May 31 09:13:08 volumio volumio[983]: verbose: CURRENT POSITION 5 May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::syncState stateService stop May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::syncState currentStatus stop May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::pushState May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:08 volumio volumio[983]: info: CoreCommandRouter::volumioPushState May 31 09:13:08 volumio volumio[983]: info: No code May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::pushState May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:08 volumio volumio[983]: info: CoreCommandRouter::volumioPushState May 31 09:13:08 volumio volumio[983]: info: ControllerMpd::pushState May 31 09:13:08 volumio volumio[983]: info: CoreCommandRouter::servicePushState May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::pushState May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:08 volumio volumio[983]: info: CoreCommandRouter::volumioPushState May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:08 volumio volumio[983]: 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} May 31 09:13:08 volumio volumio[983]: verbose: CURRENT POSITION 5 May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::syncState stateService stop May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::syncState currentStatus stop May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::pushState May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:08 volumio volumio[983]: info: CoreCommandRouter::volumioPushState May 31 09:13:08 volumio volumio[983]: info: No code May 31 09:13:08 volumio volumio[983]: info: CoreStateMachine::pushState May 31 09:13:08 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:08 volumio volumio[983]: info: CoreCommandRouter::volumioPushState May 31 09:13:08 volumio volumio[983]: info: ------------------------------ 67ms May 31 09:13:08 volumio volumio[983]: info: ------------------------------ 67ms May 31 09:13:08 volumio volumio[983]: info: May 31 09:13:08 volumio volumio[983]: ---------------------------- MPD announces system playlist update May 31 09:13:08 volumio volumio[983]: info: Ignoring MPD Status Update May 31 09:13:08 volumio volumio[983]: info: May 31 09:13:08 volumio volumio[983]: ---------------------------- MPD announces system playlist update May 31 09:13:08 volumio volumio[983]: info: Ignoring MPD Status Update May 31 09:13:08 volumio volumio[983]: error: updateQueue error: null May 31 09:13:08 volumio volumio[983]: info: May 31 09:13:08 volumio volumio[983]: ---------------------------- MPD announces system playlist update May 31 09:13:08 volumio volumio[983]: info: Ignoring MPD Status Update May 31 09:13:08 volumio volumio[983]: info: ------------------------------ 68ms May 31 09:13:08 volumio volumio[983]: info: sendMpdCommand playlistinfo took 66 milliseconds May 31 09:13:08 volumio volumio[983]: info: sendMpdCommand add "INTERNAL/LFR_20250321153000_20250321171000.aac" took 66 milliseconds May 31 09:13:08 volumio volumio[983]: info: ------------------------------ 9ms May 31 09:13:08 volumio volumio[983]: info: ------------------------------ 8ms May 31 09:13:08 volumio volumio[983]: verbose: ControllerMpd::parseTrackInfo May 31 09:13:08 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand play May 31 09:13:08 volumio volumio[983]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 31 09:13:08 volumio volumio[983]: info: ------------------------------ 86ms May 31 09:13:08 volumio volumio[983]: info: May 31 09:13:08 volumio volumio[983]: ---------------------------- MPD announces system playlist update May 31 09:13:08 volumio volumio[983]: info: Ignoring MPD Status Update May 31 09:13:08 volumio volumio[983]: info: May 31 09:13:08 volumio volumio[983]: ---------------------------- MPD announces system playlist update May 31 09:13:08 volumio volumio[983]: info: Ignoring MPD Status Update May 31 09:13:08 volumio volumio[983]: info: ------------------------------ 27ms May 31 09:13:08 volumio volumio[983]: info: sendMpdCommand play took 22 milliseconds May 31 09:13:08 volumio volumio[983]: info: ------------------------------ 19ms May 31 09:13:08 volumio volumio[983]: info: ------------------------------ 17ms May 31 09:13:09 volumio volumio[983]: info: May 31 09:13:09 volumio volumio[983]: ---------------------------- MPD announces state update: player May 31 09:13:09 volumio volumio[983]: info: ControllerMpd::getState May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand status May 31 09:13:09 volumio volumio[983]: info: May 31 09:13:09 volumio volumio[983]: ---------------------------- MPD announces state update: player May 31 09:13:09 volumio volumio[983]: info: ControllerMpd::getState May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand status May 31 09:13:09 volumio volumio[983]: info: May 31 09:13:09 volumio volumio[983]: ---------------------------- MPD announces state update: player May 31 09:13:09 volumio volumio[983]: info: May 31 09:13:09 volumio volumio[983]: ---------------------------- MPD announces state update: player May 31 09:13:09 volumio volumio[983]: info: ControllerMpd::getState May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand status May 31 09:13:09 volumio volumio[983]: info: sendMpdCommand status took 6 milliseconds May 31 09:13:09 volumio volumio[983]: info: ControllerMpd::getState May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand status May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::parseState May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 09:13:09 volumio volumio[983]: info: May 31 09:13:09 volumio volumio[983]: ---------------------------- MPD announces state update: player May 31 09:13:09 volumio volumio[983]: info: ControllerMpd::getState May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand status May 31 09:13:09 volumio volumio[983]: info: May 31 09:13:09 volumio volumio[983]: ---------------------------- MPD announces state update: player May 31 09:13:09 volumio volumio[983]: info: ControllerMpd::getState May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand status May 31 09:13:09 volumio volumio[983]: info: sendMpdCommand status took 13 milliseconds May 31 09:13:09 volumio volumio[983]: info: sendMpdCommand status took 10 milliseconds May 31 09:13:09 volumio volumio[983]: info: sendMpdCommand status took 11 milliseconds May 31 09:13:09 volumio volumio[983]: info: sendMpdCommand playlistinfo took 10 milliseconds May 31 09:13:09 volumio volumio[983]: info: sendMpdCommand status took 7 milliseconds May 31 09:13:09 volumio volumio[983]: info: sendMpdCommand status took 6 milliseconds May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::parseState May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::parseState May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::parseState May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::parseTrackInfo May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::parseState May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::parseState May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 09:13:09 volumio volumio[983]: info: ControllerMpd::pushState May 31 09:13:09 volumio volumio[983]: info: CoreCommandRouter::servicePushState May 31 09:13:09 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:09 volumio volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":5841,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"LFR_20250321153000_20250321171000.aac","artist":null,"album":null,"uri":"INTERNAL/LFR_20250321153000_20250321171000.aac","trackType":"aac"} May 31 09:13:09 volumio volumio[983]: verbose: CURRENT POSITION 5 May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::syncState stateService play May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::syncState currentStatus stop May 31 09:13:09 volumio volumio[983]: info: ------------------------------ 32ms May 31 09:13:09 volumio volumio[983]: info: sendMpdCommand playlistinfo took 10 milliseconds May 31 09:13:09 volumio volumio[983]: info: sendMpdCommand playlistinfo took 10 milliseconds May 31 09:13:09 volumio volumio[983]: info: sendMpdCommand playlistinfo took 9 milliseconds May 31 09:13:09 volumio volumio[983]: info: sendMpdCommand playlistinfo took 8 milliseconds May 31 09:13:09 volumio volumio[983]: info: sendMpdCommand playlistinfo took 9 milliseconds May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::parseTrackInfo May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::parseTrackInfo May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::parseTrackInfo May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::parseTrackInfo May 31 09:13:09 volumio volumio[983]: verbose: ControllerMpd::parseTrackInfo May 31 09:13:09 volumio volumio[983]: info: ControllerMpd::pushState May 31 09:13:09 volumio volumio[983]: info: CoreCommandRouter::servicePushState May 31 09:13:09 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:09 volumio volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":5841,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"LFR_20250321153000_20250321171000.aac","artist":null,"album":null,"uri":"INTERNAL/LFR_20250321153000_20250321171000.aac","trackType":"aac"} May 31 09:13:09 volumio volumio[983]: verbose: CURRENT POSITION 5 May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::syncState stateService play May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::syncState currentStatus play May 31 09:13:09 volumio volumio[983]: info: Received an update from plugin. extracting info from payload May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::pushState May 31 09:13:09 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:09 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 09:13:09 volumio volumio[983]: info: CoreCommandRouter::volumioPushState May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::pushState May 31 09:13:09 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:09 volumio volumio[983]: info: CoreCommandRouter::volumioPushState May 31 09:13:09 volumio volumio[983]: info: ControllerMpd::pushState May 31 09:13:09 volumio volumio[983]: info: CoreCommandRouter::servicePushState May 31 09:13:09 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:09 volumio volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":5841,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"LFR_20250321153000_20250321171000.aac","artist":null,"album":null,"uri":"INTERNAL/LFR_20250321153000_20250321171000.aac","trackType":"aac"} May 31 09:13:09 volumio volumio[983]: verbose: CURRENT POSITION 5 May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::syncState stateService play May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::syncState currentStatus play May 31 09:13:09 volumio volumio[983]: info: Received an update from plugin. extracting info from payload May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::pushState May 31 09:13:09 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:09 volumio volumio[983]: info: CoreCommandRouter::volumioPushState May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::pushState May 31 09:13:09 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:09 volumio volumio[983]: info: CoreCommandRouter::volumioPushState May 31 09:13:09 volumio volumio[983]: info: ControllerMpd::pushState May 31 09:13:09 volumio volumio[983]: info: CoreCommandRouter::servicePushState May 31 09:13:09 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:09 volumio volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":5841,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"LFR_20250321153000_20250321171000.aac","artist":null,"album":null,"uri":"INTERNAL/LFR_20250321153000_20250321171000.aac","trackType":"aac"} May 31 09:13:09 volumio volumio[983]: verbose: CURRENT POSITION 5 May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::syncState stateService play May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::syncState currentStatus play May 31 09:13:09 volumio volumio[983]: info: Received an update from plugin. extracting info from payload May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::pushState May 31 09:13:09 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:09 volumio volumio[983]: info: CoreCommandRouter::volumioPushState May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::pushState May 31 09:13:09 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:09 volumio volumio[983]: info: CoreCommandRouter::volumioPushState May 31 09:13:09 volumio volumio[983]: info: ControllerMpd::pushState May 31 09:13:09 volumio volumio[983]: info: CoreCommandRouter::servicePushState May 31 09:13:09 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:09 volumio volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":5841,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"LFR_20250321153000_20250321171000.aac","artist":null,"album":null,"uri":"INTERNAL/LFR_20250321153000_20250321171000.aac","trackType":"aac"} May 31 09:13:09 volumio volumio[983]: verbose: CURRENT POSITION 5 May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::syncState stateService play May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::syncState currentStatus play May 31 09:13:09 volumio volumio[983]: info: Received an update from plugin. extracting info from payload May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::pushState May 31 09:13:09 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:09 volumio volumio[983]: info: CoreCommandRouter::volumioPushState May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::pushState May 31 09:13:09 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:09 volumio volumio[983]: info: CoreCommandRouter::volumioPushState May 31 09:13:09 volumio volumio[983]: info: ControllerMpd::pushState May 31 09:13:09 volumio volumio[983]: info: CoreCommandRouter::servicePushState May 31 09:13:09 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:09 volumio volumio[983]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":5841,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"LFR_20250321153000_20250321171000.aac","artist":null,"album":null,"uri":"INTERNAL/LFR_20250321153000_20250321171000.aac","trackType":"aac"} May 31 09:13:09 volumio volumio[983]: verbose: CURRENT POSITION 5 May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::syncState stateService play May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::syncState currentStatus play May 31 09:13:09 volumio volumio[983]: info: Received an update from plugin. extracting info from payload May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::pushState May 31 09:13:09 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:09 volumio volumio[983]: info: CoreCommandRouter::volumioPushState May 31 09:13:09 volumio volumio[983]: info: CoreStateMachine::pushState May 31 09:13:09 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:09 volumio volumio[983]: info: CoreCommandRouter::volumioPushState May 31 09:13:09 volumio volumio[983]: info: ------------------------------ 164ms May 31 09:13:09 volumio volumio[983]: info: ------------------------------ 162ms May 31 09:13:09 volumio volumio[983]: info: ------------------------------ 162ms May 31 09:13:09 volumio volumio[983]: info: ------------------------------ 157ms May 31 09:13:09 volumio volumio[983]: info: ------------------------------ 155ms May 31 09:13:19 volumio volumio[983]: info: CoreCommandRouter::volumioPause May 31 09:13:19 volumio volumio[983]: info: CoreStateMachine::pause May 31 09:13:19 volumio volumio[983]: info: CoreStateMachine::stPlaybackTimer May 31 09:13:19 volumio volumio[983]: info: CoreStateMachine::servicePause May 31 09:13:19 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:19 volumio volumio[983]: info: CoreCommandRouter::servicePause May 31 09:13:19 volumio volumio[983]: info: ControllerMpd::pause May 31 09:13:19 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand pause May 31 09:13:19 volumio volumio[983]: info: May 31 09:13:19 volumio volumio[983]: ---------------------------- MPD announces state update: player May 31 09:13:19 volumio volumio[983]: info: sendMpdCommand pause took 4 milliseconds May 31 09:13:19 volumio volumio[983]: info: ControllerMpd::getState May 31 09:13:19 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand status May 31 09:13:19 volumio volumio[983]: info: May 31 09:13:19 volumio volumio[983]: ---------------------------- MPD announces state update: player May 31 09:13:19 volumio volumio[983]: info: ControllerMpd::getState May 31 09:13:19 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand status May 31 09:13:19 volumio volumio[983]: info: May 31 09:13:19 volumio volumio[983]: ---------------------------- MPD announces state update: player May 31 09:13:19 volumio volumio[983]: info: ControllerMpd::getState May 31 09:13:19 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand status May 31 09:13:19 volumio volumio[983]: info: sendMpdCommand status took 6 milliseconds May 31 09:13:19 volumio volumio[983]: info: sendMpdCommand status took 5 milliseconds May 31 09:13:19 volumio volumio[983]: info: sendMpdCommand status took 3 milliseconds May 31 09:13:19 volumio volumio[983]: verbose: ControllerMpd::parseState May 31 09:13:19 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 09:13:19 volumio volumio[983]: verbose: ControllerMpd::parseState May 31 09:13:19 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 09:13:19 volumio volumio[983]: verbose: ControllerMpd::parseState May 31 09:13:19 volumio volumio[983]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 09:13:19 volumio volumio[983]: info: sendMpdCommand playlistinfo took 4 milliseconds May 31 09:13:19 volumio volumio[983]: info: sendMpdCommand playlistinfo took 3 milliseconds May 31 09:13:19 volumio volumio[983]: info: sendMpdCommand playlistinfo took 3 milliseconds May 31 09:13:19 volumio volumio[983]: verbose: ControllerMpd::parseTrackInfo May 31 09:13:19 volumio volumio[983]: verbose: ControllerMpd::parseTrackInfo May 31 09:13:19 volumio volumio[983]: verbose: ControllerMpd::parseTrackInfo May 31 09:13:19 volumio volumio[983]: info: ControllerMpd::pushState May 31 09:13:19 volumio volumio[983]: info: CoreCommandRouter::servicePushState May 31 09:13:19 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:19 volumio volumio[983]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10730,"duration":5841,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"49 Kbps","isStreaming":false,"title":"LFR_20250321153000_20250321171000.aac","artist":null,"album":null,"uri":"INTERNAL/LFR_20250321153000_20250321171000.aac","trackType":"aac"} May 31 09:13:19 volumio volumio[983]: verbose: CURRENT POSITION 5 May 31 09:13:19 volumio volumio[983]: info: CoreStateMachine::syncState stateService pause May 31 09:13:19 volumio volumio[983]: info: CoreStateMachine::syncState currentStatus pause May 31 09:13:19 volumio volumio[983]: info: CoreStateMachine::pushState May 31 09:13:19 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:19 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 09:13:19 volumio volumio[983]: info: CoreCommandRouter::volumioPushState May 31 09:13:19 volumio volumio[983]: info: CoreStateMachine::stPlaybackTimer May 31 09:13:19 volumio volumio[983]: info: ControllerMpd::pushState May 31 09:13:19 volumio volumio[983]: info: CoreCommandRouter::servicePushState May 31 09:13:19 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:19 volumio volumio[983]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10730,"duration":5841,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"49 Kbps","isStreaming":false,"title":"LFR_20250321153000_20250321171000.aac","artist":null,"album":null,"uri":"INTERNAL/LFR_20250321153000_20250321171000.aac","trackType":"aac"} May 31 09:13:19 volumio volumio[983]: verbose: CURRENT POSITION 5 May 31 09:13:19 volumio volumio[983]: info: CoreStateMachine::syncState stateService pause May 31 09:13:19 volumio volumio[983]: info: CoreStateMachine::syncState currentStatus pause May 31 09:13:19 volumio volumio[983]: info: CoreStateMachine::pushState May 31 09:13:19 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:19 volumio volumio[983]: info: CoreCommandRouter::volumioPushState May 31 09:13:19 volumio volumio[983]: info: CoreStateMachine::stPlaybackTimer May 31 09:13:19 volumio volumio[983]: info: ControllerMpd::pushState May 31 09:13:19 volumio volumio[983]: info: CoreCommandRouter::servicePushState May 31 09:13:19 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:19 volumio volumio[983]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10730,"duration":5841,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"49 Kbps","isStreaming":false,"title":"LFR_20250321153000_20250321171000.aac","artist":null,"album":null,"uri":"INTERNAL/LFR_20250321153000_20250321171000.aac","trackType":"aac"} May 31 09:13:19 volumio volumio[983]: verbose: CURRENT POSITION 5 May 31 09:13:19 volumio volumio[983]: info: CoreStateMachine::syncState stateService pause May 31 09:13:19 volumio volumio[983]: info: CoreStateMachine::syncState currentStatus pause May 31 09:13:19 volumio volumio[983]: info: CoreStateMachine::pushState May 31 09:13:19 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:19 volumio volumio[983]: info: CoreCommandRouter::volumioPushState May 31 09:13:19 volumio volumio[983]: info: CoreStateMachine::stPlaybackTimer May 31 09:13:19 volumio volumio[983]: info: ------------------------------ 52ms May 31 09:13:19 volumio volumio[983]: info: ------------------------------ 50ms May 31 09:13:19 volumio volumio[983]: info: ------------------------------ 49ms May 31 09:13:27 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 31 09:13:27 volumio volumio[983]: info: Preload queue cleared May 31 09:13:34 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 09:13:34 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 09:13:34 volumio volumio[983]: info: Discovery: Getting this device information May 31 09:13:34 volumio volumio[983]: info: CoreCommandRouter::volumioGetState May 31 09:13:34 volumio volumio[983]: info: CorePlayQueue::getTrack 5 May 31 09:13:34 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 09:13:34 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 31 09:13:34 volumio dbus-daemon[529]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.41' (uid=0 pid=3027 comm="timedatectl show --property=NTPSynchronized --valu") May 31 09:13:34 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 31 09:13:35 volumio dbus-daemon[529]: [system] Successfully activated service 'org.freedesktop.timedate1' May 31 09:13:35 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 31 09:13:35 volumio setdatetime-helper.sh[3026]: Time is already synchronized. May 31 09:13:35 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 31 09:13:35 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 31 09:13:40 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 09:13:40 volumio volumio[983]: info: CURURI: playlists May 31 09:13:40 volumio volumio[983]: info: Listing playlists May 31 09:13:40 volumio volumio[983]: info: Preload queue cleared May 31 09:13:56 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 31 09:13:56 volumio volumio[983]: info: Preload queue cleared May 31 09:13:59 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 31 09:13:59 volumio volumio[983]: info: READ May 31 09:13:59 volumio volumio[983]: Use XMLElement.setAttribute instead May 31 09:13:59 volumio volumio[983]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 09:13:59 volumio volumio[983]: TypeError: children[i].attr(...).value is not a function May 31 09:13:59 volumio volumio[983]: at /volumio/app/plugins/music_service/webradio/index.js:286:47 May 31 09:13:59 volumio volumio[983]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 31 09:13:59 volumio volumio[983]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 09:14:00 volumio sudo[3088]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-31 09:13' May 31 09:14:00 volumio sudo[3088]: 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"