Mar 28 14:20:01 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioGetState Mar 28 14:20:01 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:20:06 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 28 14:20:06 aschejukebox volumio[1359]: info: CURURI: music-library Mar 28 14:20:06 aschejukebox volumio[1359]: info: Preload queue cleared Mar 28 14:20:08 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 28 14:20:08 aschejukebox volumio[1359]: info: CURURI: music-library/INTERNAL Mar 28 14:20:08 aschejukebox volumio[1359]: info: Preload queue cleared Mar 28 14:20:10 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 28 14:20:10 aschejukebox volumio[1359]: info: CURURI: music-library/INTERNAL/media Mar 28 14:20:10 aschejukebox volumio[1359]: error: Failed LSINFO: null Mar 28 14:20:10 aschejukebox volumio[1359]: info: Preload queue cleared Mar 28 14:20:38 aschejukebox nmbd[1242]: [2026/03/28 14:20:38.138297, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 28 14:20:38 aschejukebox nmbd[1242]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.88.18 for name WORKGROUP<1d>. Mar 28 14:20:38 aschejukebox nmbd[1242]: This response was from IP 192.168.88.110, reporting an IP address of 192.168.88.110. Mar 28 14:20:41 aschejukebox sudo[2465]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/mount /dev/nvme0n1p4 /data/INTERNAL/media Mar 28 14:20:41 aschejukebox sudo[2465]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Mar 28 14:20:41 aschejukebox sudo[2465]: pam_unix(sudo:session): session closed for user root Mar 28 14:20:49 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 28 14:20:49 aschejukebox volumio[1359]: info: CURURI: music-library/INTERNAL/media Mar 28 14:20:49 aschejukebox volumio[1359]: error: Failed LSINFO: null Mar 28 14:20:49 aschejukebox volumio[1359]: info: Preload queue cleared Mar 28 14:20:57 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioGetState Mar 28 14:20:57 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:01 aschejukebox volumio[1359]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 14:21:01 aschejukebox volumio[1359]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 14:21:01 aschejukebox volumio[1359]: info: Retrieving Cloud Streaming UI Mar 28 14:21:01 aschejukebox volumio[1359]: info: Getting Tidal Cloud Configuration Mar 28 14:21:01 aschejukebox volumio[1359]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 14:21:01 aschejukebox volumio[1359]: info: Getting Qobuz Cloud Configuration Mar 28 14:21:01 aschejukebox volumio[1359]: info: Asking plugin for UI Config Mar 28 14:21:01 aschejukebox volumio[1359]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 14:21:01 aschejukebox volumio[1359]: info: Getting Spotify Cloud Configuration Mar 28 14:21:01 aschejukebox volumio[1359]: info: Asking plugin for UI Config Mar 28 14:21:01 aschejukebox volumio[1359]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 14:21:01 aschejukebox volumio[1359]: info: Saving Spotify Acccount Mar 28 14:21:01 aschejukebox volumio[1359]: info: Got Tidal Cloud Configuration Mar 28 14:21:01 aschejukebox volumio[1359]: info: Got it Mar 28 14:21:01 aschejukebox volumio[1359]: info: Got it Mar 28 14:21:01 aschejukebox volumio[1359]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 28 14:21:01 aschejukebox volumio[1359]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 14:21:01 aschejukebox volumio[1359]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Mar 28 14:21:01 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 28 14:21:01 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 28 14:21:01 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 28 14:21:01 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 28 14:21:01 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 14:21:01 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 14:21:01 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 14:21:01 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 14:21:01 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioGetBrowseSources Mar 28 14:21:01 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioGetBrowseSources Mar 28 14:21:01 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioGetBrowseSources Mar 28 14:21:01 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 28 14:21:01 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 28 14:21:04 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Mar 28 14:21:04 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand rescan Mar 28 14:21:04 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:04 aschejukebox volumio[1359]: info: Mar 28 14:21:04 aschejukebox volumio[1359]: ---------------------------- MPD announces state update: update Mar 28 14:21:04 aschejukebox volumio[1359]: info: sendMpdCommand rescan took 2 milliseconds Mar 28 14:21:04 aschejukebox volumio[1359]: info: ControllerMpd::getState Mar 28 14:21:04 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:04 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:04 aschejukebox volumio[1359]: info: Mar 28 14:21:04 aschejukebox volumio[1359]: ---------------------------- MPD announces state update: update Mar 28 14:21:04 aschejukebox volumio[1359]: info: ControllerMpd::getState Mar 28 14:21:04 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:04 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:04 aschejukebox volumio[1359]: info: Mar 28 14:21:04 aschejukebox volumio[1359]: ---------------------------- MPD announces state update: update Mar 28 14:21:04 aschejukebox volumio[1359]: info: ControllerMpd::getState Mar 28 14:21:04 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:04 aschejukebox volumio[1359]: info: sendMpdCommand status took 2 milliseconds Mar 28 14:21:04 aschejukebox volumio[1359]: info: sendMpdCommand status took 2 milliseconds Mar 28 14:21:04 aschejukebox volumio[1359]: info: sendMpdCommand status took 1 milliseconds Mar 28 14:21:04 aschejukebox volumio[1359]: info: sendMpdCommand status took 1 milliseconds Mar 28 14:21:04 aschejukebox volumio[1359]: info: sendMpdCommand status took 1 milliseconds Mar 28 14:21:04 aschejukebox volumio[1359]: info: sendMpdCommand status took 0 milliseconds Mar 28 14:21:04 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:05 aschejukebox volumio[1359]: info: Command Router : Notfying DB Updatetrue Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreCommandRouter::Close All Modals sent Mar 28 14:21:05 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:05 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:05 aschejukebox volumio[1359]: info: Command Router : Notfying DB Updatetrue Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreCommandRouter::Close All Modals sent Mar 28 14:21:05 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:05 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:05 aschejukebox volumio[1359]: info: Command Router : Notfying DB Updatetrue Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreCommandRouter::Close All Modals sent Mar 28 14:21:05 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:05 aschejukebox volumio[1359]: info: ControllerMpd::pushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreCommandRouter::servicePushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:05 aschejukebox volumio[1359]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 14:21:05 aschejukebox volumio[1359]: verbose: CURRENT POSITION 0 Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreStateMachine::syncState stateService stop Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreStateMachine::syncState currentStatus stop Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: No code Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: ControllerMpd::pushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreCommandRouter::servicePushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:05 aschejukebox volumio[1359]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 14:21:05 aschejukebox volumio[1359]: verbose: CURRENT POSITION 0 Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreStateMachine::syncState stateService stop Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreStateMachine::syncState currentStatus stop Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: No code Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: ControllerMpd::pushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreCommandRouter::servicePushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:05 aschejukebox volumio[1359]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 14:21:05 aschejukebox volumio[1359]: verbose: CURRENT POSITION 0 Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreStateMachine::syncState stateService stop Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreStateMachine::syncState currentStatus stop Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: No code Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:05 aschejukebox volumio[1359]: info: ------------------------------ 929ms Mar 28 14:21:05 aschejukebox volumio[1359]: info: ------------------------------ 928ms Mar 28 14:21:05 aschejukebox volumio[1359]: info: ------------------------------ 928ms Mar 28 14:21:05 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 28 14:21:06 aschejukebox systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Mar 28 14:21:06 aschejukebox systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Mar 28 14:21:06 aschejukebox systemd[1]: welcome.service: Deactivated successfully. Mar 28 14:21:06 aschejukebox systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 28 14:21:06 aschejukebox systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 28 14:21:06 aschejukebox systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 28 14:21:06 aschejukebox welcome[2529]: Resolved ip:[1] 192.168.88.18 Mar 28 14:21:06 aschejukebox systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 28 14:21:06 aschejukebox systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Mar 28 14:21:07 aschejukebox volumio[1359]: info: Received Get System Info Mar 28 14:21:07 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 14:21:07 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 14:21:07 aschejukebox volumio[1359]: info: Discovery: Getting this device information Mar 28 14:21:07 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioGetState Mar 28 14:21:07 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:07 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 14:21:07 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 28 14:21:07 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 28 14:21:07 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 28 14:21:07 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand update Mar 28 14:21:07 aschejukebox volumio[1359]: info: sendMpdCommand update took 1 milliseconds Mar 28 14:21:08 aschejukebox volumio5-onboarding[1585]: time=2026-03-28T14:21:08.137-05:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 28 14:21:09 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 28 14:21:13 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 28 14:21:15 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioGetState Mar 28 14:21:15 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:19 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 28 14:21:19 aschejukebox volumio[1359]: info: CURURI: music-library Mar 28 14:21:19 aschejukebox volumio[1359]: info: Preload queue cleared Mar 28 14:21:22 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 28 14:21:22 aschejukebox volumio[1359]: info: CURURI: music-library/INTERNAL Mar 28 14:21:22 aschejukebox volumio[1359]: info: Preload queue cleared Mar 28 14:21:23 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 28 14:21:23 aschejukebox volumio[1359]: info: CURURI: music-library/INTERNAL/media Mar 28 14:21:23 aschejukebox volumio[1359]: info: Preload queue cleared Mar 28 14:21:24 aschejukebox volumio[1359]: info: MPD Database updated - AlbumList cache refreshed Mar 28 14:21:24 aschejukebox volumio[1359]: info: Mar 28 14:21:24 aschejukebox volumio[1359]: ---------------------------- MPD announces state update: database Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:24 aschejukebox volumio[1359]: info: Mar 28 14:21:24 aschejukebox volumio[1359]: ---------------------------- MPD announces state update: update Mar 28 14:21:24 aschejukebox volumio[1359]: info: ControllerMpd::getState Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:24 aschejukebox volumio[1359]: info: ControllerMpd::getState Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:24 aschejukebox volumio[1359]: info: MPD Database updated - AlbumList cache refreshed Mar 28 14:21:24 aschejukebox volumio[1359]: info: Mar 28 14:21:24 aschejukebox volumio[1359]: ---------------------------- MPD announces state update: database Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:24 aschejukebox volumio[1359]: info: Mar 28 14:21:24 aschejukebox volumio[1359]: ---------------------------- MPD announces state update: update Mar 28 14:21:24 aschejukebox volumio[1359]: info: ControllerMpd::getState Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:24 aschejukebox volumio[1359]: info: ControllerMpd::getState Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:24 aschejukebox volumio[1359]: info: MPD Database updated - AlbumList cache refreshed Mar 28 14:21:24 aschejukebox volumio[1359]: info: Mar 28 14:21:24 aschejukebox volumio[1359]: ---------------------------- MPD announces state update: database Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:24 aschejukebox volumio[1359]: info: Mar 28 14:21:24 aschejukebox volumio[1359]: ---------------------------- MPD announces state update: update Mar 28 14:21:24 aschejukebox volumio[1359]: info: ControllerMpd::getState Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:24 aschejukebox volumio[1359]: info: ControllerMpd::getState Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:24 aschejukebox volumio[1359]: info: sendMpdCommand status took 32 milliseconds Mar 28 14:21:24 aschejukebox volumio[1359]: info: sendMpdCommand status took 33 milliseconds Mar 28 14:21:24 aschejukebox volumio[1359]: info: sendMpdCommand status took 33 milliseconds Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:24 aschejukebox volumio[1359]: info: Command Router : Notfying DB Updatetrue Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::Close All Modals sent Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:24 aschejukebox volumio[1359]: info: ControllerMpd::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::servicePushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: CURRENT POSITION 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::syncState stateService stop Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::syncState currentStatus stop Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: No code Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: ControllerMpd::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::servicePushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: CURRENT POSITION 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::syncState stateService stop Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::syncState currentStatus stop Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: No code Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: ------------------------------ 70ms Mar 28 14:21:24 aschejukebox volumio[1359]: info: ------------------------------ 71ms Mar 28 14:21:24 aschejukebox volumio[1359]: info: sendMpdCommand status took 84 milliseconds Mar 28 14:21:24 aschejukebox volumio[1359]: info: sendMpdCommand status took 84 milliseconds Mar 28 14:21:24 aschejukebox volumio[1359]: info: sendMpdCommand status took 84 milliseconds Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:24 aschejukebox volumio[1359]: info: Command Router : Notfying DB Updatetrue Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::Close All Modals sent Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:24 aschejukebox volumio[1359]: info: ControllerMpd::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::servicePushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: CURRENT POSITION 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::syncState stateService stop Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::syncState currentStatus stop Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: No code Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: ControllerMpd::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::servicePushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: CURRENT POSITION 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::syncState stateService stop Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::syncState currentStatus stop Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: No code Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: ------------------------------ 118ms Mar 28 14:21:24 aschejukebox volumio[1359]: info: ------------------------------ 117ms Mar 28 14:21:24 aschejukebox volumio[1359]: info: sendMpdCommand status took 138 milliseconds Mar 28 14:21:24 aschejukebox volumio[1359]: info: sendMpdCommand status took 138 milliseconds Mar 28 14:21:24 aschejukebox volumio[1359]: info: sendMpdCommand status took 138 milliseconds Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:24 aschejukebox volumio[1359]: info: Command Router : Notfying DB Updatetrue Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::Close All Modals sent Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:24 aschejukebox volumio[1359]: info: ControllerMpd::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::servicePushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: CURRENT POSITION 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::syncState stateService stop Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::syncState currentStatus stop Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: No code Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: ControllerMpd::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::servicePushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: CURRENT POSITION 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::syncState stateService stop Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::syncState currentStatus stop Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: No code Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: ------------------------------ 157ms Mar 28 14:21:24 aschejukebox volumio[1359]: info: ------------------------------ 157ms Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:24 aschejukebox volumio[1359]: info: Mar 28 14:21:24 aschejukebox volumio[1359]: ---------------------------- MPD announces state update: update Mar 28 14:21:24 aschejukebox volumio[1359]: info: ControllerMpd::getState Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:24 aschejukebox volumio[1359]: info: Mar 28 14:21:24 aschejukebox volumio[1359]: ---------------------------- MPD announces state update: update Mar 28 14:21:24 aschejukebox volumio[1359]: info: ControllerMpd::getState Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:24 aschejukebox volumio[1359]: info: Mar 28 14:21:24 aschejukebox volumio[1359]: ---------------------------- MPD announces state update: update Mar 28 14:21:24 aschejukebox volumio[1359]: info: ControllerMpd::getState Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Mar 28 14:21:24 aschejukebox volumio[1359]: info: sendMpdCommand status took 1 milliseconds Mar 28 14:21:24 aschejukebox volumio[1359]: info: sendMpdCommand status took 1 milliseconds Mar 28 14:21:24 aschejukebox volumio[1359]: info: sendMpdCommand status took 0 milliseconds Mar 28 14:21:24 aschejukebox volumio[1359]: info: sendMpdCommand status took 0 milliseconds Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:24 aschejukebox volumio[1359]: info: Command Router : Notfying DB Updatefalse Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::Close All Modals sent Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:24 aschejukebox volumio[1359]: info: Command Router : Notfying DB Updatefalse Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::Close All Modals sent Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:24 aschejukebox volumio[1359]: info: ControllerMpd::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::servicePushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: CURRENT POSITION 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::syncState stateService stop Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::syncState currentStatus stop Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: No code Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: ControllerMpd::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::servicePushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: CURRENT POSITION 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::syncState stateService stop Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::syncState currentStatus stop Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: No code Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: ------------------------------ 23ms Mar 28 14:21:24 aschejukebox volumio[1359]: info: ------------------------------ 22ms Mar 28 14:21:24 aschejukebox volumio[1359]: info: sendMpdCommand status took 23 milliseconds Mar 28 14:21:24 aschejukebox volumio[1359]: info: sendMpdCommand status took 23 milliseconds Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:24 aschejukebox volumio[1359]: info: Command Router : Notfying DB Updatefalse Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::Close All Modals sent Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: ControllerMpd::parseState Mar 28 14:21:24 aschejukebox volumio[1359]: info: ControllerMpd::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::servicePushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 14:21:24 aschejukebox volumio[1359]: verbose: CURRENT POSITION 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::syncState stateService stop Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::syncState currentStatus stop Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: No code Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreStateMachine::pushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: CorePlayQueue::getTrack 0 Mar 28 14:21:24 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushState Mar 28 14:21:24 aschejukebox volumio[1359]: info: ------------------------------ 35ms Mar 28 14:21:26 aschejukebox volumio[1359]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 28 14:21:26 aschejukebox volumio[1359]: info: CURURI: music-library/INTERNAL/media/Bloodshot Bill Mar 28 14:21:26 aschejukebox volumio[1359]: info: Preload queue cleared Mar 28 14:21:28 aschejukebox volumio[1359]: info: Preload queue cleared Mar 28 14:21:28 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 14:21:28 aschejukebox volumio[1359]: info: CoreStateMachine::ClearQueue Mar 28 14:21:28 aschejukebox volumio[1359]: info: CoreStateMachine::stop Mar 28 14:21:28 aschejukebox volumio[1359]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 14:21:28 aschejukebox volumio[1359]: info: CorePlayQueue::clearPlayQueue Mar 28 14:21:28 aschejukebox volumio[1359]: info: CorePlayQueue::saveQueue Mar 28 14:21:28 aschejukebox volumio[1359]: info: CoreCommandRouter::volumioPushQueue Mar 28 14:21:28 aschejukebox volumio[1359]: info: CoreStateMachine::addQueueItems Mar 28 14:21:28 aschejukebox volumio[1359]: info: CorePlayQueue::addQueueItems Mar 28 14:21:28 aschejukebox volumio[1359]: info: Preload queue cleared Mar 28 14:21:28 aschejukebox volumio[1359]: info: Adding Item to queue: music-library/INTERNAL/media/Bloodshot Bill/Bloodshot Bill & Televisionaries - Spit on My Rubber - EP Mar 28 14:21:28 aschejukebox volumio[1359]: info: Exploding uri music-library/INTERNAL/media/Bloodshot Bill/Bloodshot Bill & Televisionaries - Spit on My Rubber - EP in service mpd Mar 28 14:21:28 aschejukebox volumio[1359]: info: ALBUMART /albumart?cacheid=123&web=//extralarge&path=%2Fmnt%2FINTERNAL%2Fmedia%2FBloodshot%20Bill%2FBloodshot%20Bill%20%26%20Televisionaries%20-%20Spit%20on%20My%20Rubber%20-%20EP%2FBloodshot%20Bill%20%26%20Televisionaries%20-%20Spit%20on%20My%20Rubber%20-%20EP%20-%2001%20Spit%20on%20My%20Rubber.wav&metadata=false Mar 28 14:21:28 aschejukebox volumio[1359]: info: URI /mnt/INTERNAL/media/Bloodshot Bill/Bloodshot Bill & Televisionaries - Spit on My Rubber - EP/Bloodshot Bill & Televisionaries - Spit on My Rubber - EP - 01 Spit on My Rubber.wav Mar 28 14:21:28 aschejukebox volumio[1359]: info: ALBUMART /albumart?cacheid=123&web=//extralarge&path=%2Fmnt%2FINTERNAL%2Fmedia%2FBloodshot%20Bill%2FBloodshot%20Bill%20%26%20Televisionaries%20-%20Spit%20on%20My%20Rubber%20-%20EP%2FBloodshot%20Bill%20%26%20Televisionaries%20-%20Spit%20on%20My%20Rubber%20-%20EP%20-%2002%20Heaven%20on%20Earth.wav&metadata=false Mar 28 14:21:28 aschejukebox volumio[1359]: info: URI /mnt/INTERNAL/media/Bloodshot Bill/Bloodshot Bill & Televisionaries - Spit on My Rubber - EP/Bloodshot Bill & Televisionaries - Spit on My Rubber - EP - 02 Heaven on Earth.wav Mar 28 14:21:28 aschejukebox volumio[1359]: info: ALBUMART /albumart?cacheid=123&web=//extralarge&path=%2Fmnt%2FINTERNAL%2Fmedia%2FBloodshot%20Bill%2FBloodshot%20Bill%20%26%20Televisionaries%20-%20Spit%20on%20My%20Rubber%20-%20EP%2FBloodshot%20Bill%20%26%20Televisionaries%20-%20Spit%20on%20My%20Rubber%20-%20EP%20-%2003%20Sha-La-Ba.wav&metadata=false Mar 28 14:21:28 aschejukebox volumio[1359]: info: URI /mnt/INTERNAL/media/Bloodshot Bill/Bloodshot Bill & Televisionaries - Spit on My Rubber - EP/Bloodshot Bill & Televisionaries - Spit on My Rubber - EP - 03 Sha-La-Ba.wav Mar 28 14:21:28 aschejukebox volumio[1359]: info: ALBUMART /albumart?cacheid=123&web=//extralarge&path=%2Fmnt%2FINTERNAL%2Fmedia%2FBloodshot%20Bill%2FBloodshot%20Bill%20%26%20Televisionaries%20-%20Spit%20on%20My%20Rubber%20-%20EP%2FBloodshot%20Bill%20%26%20Televisionaries%20-%20Spit%20on%20My%20Rubber%20-%20EP%20-%2004%20Give%20Me%20Something.wav&metadata=false Mar 28 14:21:28 aschejukebox volumio[1359]: info: URI /mnt/INTERNAL/media/Bloodshot Bill/Bloodshot Bill & Televisionaries - Spit on My Rubber - EP/Bloodshot Bill & Televisionaries - Spit on My Rubber - EP - 04 Give Me Something.wav Mar 28 14:21:28 aschejukebox volumio[1359]: info: ALBUMART /albumart?cacheid=123&web=//extralarge&path=%2Fmnt%2FINTERNAL%2Fmedia%2FBloodshot%20Bill%2FBloodshot%20Bill%20%26%20Televisionaries%20-%20Spit%20on%20My%20Rubber%20-%20EP%2FBloodshot%20Bill%20%26%20Televisionaries%20-%20Spit%20on%20My%20Rubber%20-%20EP.zip&metadata=false Mar 28 14:21:28 aschejukebox volumio[1359]: info: URI /mnt/INTERNAL/media/Bloodshot Bill/Bloodshot Bill & Televisionaries - Spit on My Rubber - EP/Bloodshot Bill & Televisionaries - Spit on My Rubber - EP.zip Mar 28 14:21:28 aschejukebox volumio[1359]: info: ALBUMART /albumart?cacheid=123&web=//extralarge&path=%2Fmnt%2FINTERNAL%2Fmedia%2FBloodshot%20Bill%2FBloodshot%20Bill%20%26%20Televisionaries%20-%20Spit%20on%20My%20Rubber%20-%20EP%2FBloodshot%20Bill%20%26%20Televisionaries%20-%20Spit%20on%20My%20Rubber%20-%20EP.zip&metadata=false Mar 28 14:21:28 aschejukebox volumio[1359]: info: URI /mnt/INTERNAL/media/Bloodshot Bill/Bloodshot Bill & Televisionaries - Spit on My Rubber - EP/Bloodshot Bill & Televisionaries - Spit on My Rubber - EP.zip Mar 28 14:21:28 aschejukebox volumio[1359]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 14:21:28 aschejukebox volumio[1359]: Error: Unable to resolve or reject the same promise twice Mar 28 14:21:28 aschejukebox volumio[1359]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 28 14:21:28 aschejukebox volumio[1359]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Mar 28 14:21:28 aschejukebox volumio[1359]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Mar 28 14:21:28 aschejukebox volumio[1359]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Mar 28 14:21:28 aschejukebox volumio[1359]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 28 14:21:28 aschejukebox volumio[1359]: at Socket.emit (node:events:514:28) Mar 28 14:21:28 aschejukebox volumio[1359]: at addChunk (node:internal/streams/readable:343:12) Mar 28 14:21:28 aschejukebox volumio[1359]: at readableAddChunk (node:internal/streams/readable:312:11) Mar 28 14:21:28 aschejukebox volumio[1359]: at Readable.push (node:internal/streams/readable:253:10) Mar 28 14:21:28 aschejukebox volumio[1359]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Mar 28 14:21:28 aschejukebox volumio[1359]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 14:21:28 aschejukebox sudo[2600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 14:20' Mar 28 14:21:28 aschejukebox sudo[2600]: 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"