Mar 25 09:20:00 volumio wpa_supplicant[1209]: RRM: Ignoring radio measurement request: Not RRM network Mar 25 09:20:02 volumio wpa_supplicant[1209]: RRM: Ignoring radio measurement request: Not RRM network Mar 25 09:20:03 volumio wpa_supplicant[1209]: RRM: Ignoring radio measurement request: Not RRM network Mar 25 09:20:04 volumio volumio[1244]: verbose: New Socket.io Connection to 192.168.1.88 from 192.168.1.217 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.3 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 5 Mar 25 09:20:04 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 25 09:20:04 volumio volumio[1244]: info: CoreCommandRouter::volumioGetVisibleSources Mar 25 09:20:04 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 09:20:04 volumio volumio[1244]: info: CoreCommandRouter::volumioGetState Mar 25 09:20:04 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:04 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 25 09:20:04 volumio volumio[1244]: info: CoreCommandRouter::volumioGetQueue Mar 25 09:20:04 volumio volumio[1244]: info: CoreStateMachine::getQueue Mar 25 09:20:04 volumio volumio[1244]: info: CorePlayQueue::getQueue Mar 25 09:20:04 volumio volumio[1244]: info: Listing playlists Mar 25 09:20:04 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 25 09:20:04 volumio volumio[1244]: info: Received Get System Info Mar 25 09:20:04 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 09:20:04 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 09:20:04 volumio volumio[1244]: info: Discovery: Getting this device information Mar 25 09:20:04 volumio volumio[1244]: info: CoreCommandRouter::volumioGetState Mar 25 09:20:04 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:04 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 09:20:04 volumio volumio[1244]: info: CoreCommandRouter::volumioGetState Mar 25 09:20:04 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:04 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 25 09:20:13 volumio sudo[2347]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/USB/7C41-4E01 Mar 25 09:20:13 volumio sudo[2347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 09:20:14 volumio systemd[1]: media-7C41\x2d4E01.mount: Deactivated successfully. Mar 25 09:20:14 volumio sudo[2347]: pam_unix(sudo:session): session closed for user root Mar 25 09:20:14 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , updateMpdDB Mar 25 09:20:14 volumio volumio[1244]: info: Update mpd DB Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand update Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:20:14 volumio volumio[1244]: info: Mar 25 09:20:14 volumio volumio[1244]: ---------------------------- MPD announces state update: update Mar 25 09:20:14 volumio volumio[1244]: info: ControllerMpd::getState Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:20:14 volumio volumio[1244]: info: Mar 25 09:20:14 volumio volumio[1244]: ---------------------------- MPD announces state update: update Mar 25 09:20:14 volumio volumio[1244]: info: ControllerMpd::getState Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:20:14 volumio volumio[1244]: info: Mar 25 09:20:14 volumio volumio[1244]: ---------------------------- MPD announces state update: update Mar 25 09:20:14 volumio volumio[1244]: info: MPD Database updated - AlbumList cache refreshed Mar 25 09:20:14 volumio volumio[1244]: info: Mar 25 09:20:14 volumio volumio[1244]: ---------------------------- MPD announces state update: database Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:20:14 volumio volumio[1244]: info: Mar 25 09:20:14 volumio volumio[1244]: ---------------------------- MPD announces state update: update Mar 25 09:20:14 volumio volumio[1244]: info: ControllerMpd::getState Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:20:14 volumio volumio[1244]: info: sendMpdCommand update took 129 milliseconds Mar 25 09:20:14 volumio volumio[1244]: info: ControllerMpd::getState Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:20:14 volumio volumio[1244]: info: ControllerMpd::getState Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:20:14 volumio volumio[1244]: info: MPD Database updated - AlbumList cache refreshed Mar 25 09:20:14 volumio volumio[1244]: info: Mar 25 09:20:14 volumio volumio[1244]: ---------------------------- MPD announces state update: database Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:20:14 volumio volumio[1244]: info: Mar 25 09:20:14 volumio volumio[1244]: ---------------------------- MPD announces state update: update Mar 25 09:20:14 volumio volumio[1244]: info: ControllerMpd::getState Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:20:14 volumio volumio[1244]: info: ControllerMpd::getState Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:20:14 volumio volumio[1244]: info: MPD Database updated - AlbumList cache refreshed Mar 25 09:20:14 volumio volumio[1244]: info: Mar 25 09:20:14 volumio volumio[1244]: ---------------------------- MPD announces state update: database Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:20:14 volumio volumio[1244]: info: Mar 25 09:20:14 volumio volumio[1244]: ---------------------------- MPD announces state update: update Mar 25 09:20:14 volumio volumio[1244]: info: ControllerMpd::getState Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:20:14 volumio volumio[1244]: info: ControllerMpd::getState Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:20:14 volumio volumio[1244]: info: Mar 25 09:20:14 volumio volumio[1244]: ---------------------------- MPD announces state update: update Mar 25 09:20:14 volumio volumio[1244]: info: sendMpdCommand status took 100 milliseconds Mar 25 09:20:14 volumio volumio[1244]: info: ControllerMpd::getState Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:20:14 volumio volumio[1244]: info: sendMpdCommand status took 101 milliseconds Mar 25 09:20:14 volumio volumio[1244]: info: sendMpdCommand status took 100 milliseconds Mar 25 09:20:14 volumio volumio[1244]: info: sendMpdCommand status took 99 milliseconds Mar 25 09:20:14 volumio volumio[1244]: info: sendMpdCommand status took 99 milliseconds Mar 25 09:20:14 volumio volumio[1244]: info: sendMpdCommand status took 55 milliseconds Mar 25 09:20:14 volumio volumio[1244]: info: sendMpdCommand status took 56 milliseconds Mar 25 09:20:14 volumio volumio[1244]: info: sendMpdCommand status took 55 milliseconds Mar 25 09:20:14 volumio volumio[1244]: info: sendMpdCommand status took 54 milliseconds Mar 25 09:20:14 volumio volumio[1244]: info: sendMpdCommand status took 53 milliseconds Mar 25 09:20:14 volumio volumio[1244]: info: sendMpdCommand status took 53 milliseconds Mar 25 09:20:14 volumio volumio[1244]: info: sendMpdCommand status took 52 milliseconds Mar 25 09:20:14 volumio volumio[1244]: info: sendMpdCommand status took 51 milliseconds Mar 25 09:20:14 volumio volumio[1244]: info: sendMpdCommand status took 50 milliseconds Mar 25 09:20:14 volumio volumio[1244]: info: sendMpdCommand status took 51 milliseconds Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:20:14 volumio volumio[1244]: info: Command Router : Notfying DB Updatefalse Mar 25 09:20:14 volumio volumio[1244]: info: CoreCommandRouter::Close All Modals sent Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 09:20:14 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:20:15 volumio volumio[1244]: info: Command Router : Notfying DB Updatefalse Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::Close All Modals sent Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:20:15 volumio volumio[1244]: info: Command Router : Notfying DB Updatefalse Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::Close All Modals sent Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:20:15 volumio volumio[1244]: info: Command Router : Notfying DB Updatefalse Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::Close All Modals sent Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:20:15 volumio volumio[1244]: info: Command Router : Notfying DB Updatefalse Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::Close All Modals sent Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:20:15 volumio volumio[1244]: info: Command Router : Notfying DB Updatefalse Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::Close All Modals sent Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 09:20:15 volumio volumio[1244]: info: sendMpdCommand status took 710 milliseconds Mar 25 09:20:15 volumio volumio[1244]: info: sendMpdCommand status took 662 milliseconds Mar 25 09:20:15 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 495 milliseconds Mar 25 09:20:15 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 464 milliseconds Mar 25 09:20:15 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 357 milliseconds Mar 25 09:20:15 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 357 milliseconds Mar 25 09:20:15 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 329 milliseconds Mar 25 09:20:15 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 249 milliseconds Mar 25 09:20:15 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 249 milliseconds Mar 25 09:20:15 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 193 milliseconds Mar 25 09:20:15 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 193 milliseconds Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:20:15 volumio volumio[1244]: info: Command Router : Notfying DB Updatefalse Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::Close All Modals sent Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Mar 25 09:20:15 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Mar 25 09:20:15 volumio volumio[1244]: info: ControllerMpd::pushState Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Mar 25 09:20:15 volumio volumio[1244]: info: CoreStateMachine::pushState Mar 25 09:20:15 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Mar 25 09:20:15 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:15 volumio volumio[1244]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 09:20:15 volumio volumio[1244]: info: ControllerMpd::pushState Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Mar 25 09:20:15 volumio volumio[1244]: info: CoreStateMachine::pushState Mar 25 09:20:15 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Mar 25 09:20:15 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:15 volumio volumio[1244]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 09:20:15 volumio volumio[1244]: info: ControllerMpd::pushState Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Mar 25 09:20:15 volumio volumio[1244]: info: CoreStateMachine::pushState Mar 25 09:20:15 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Mar 25 09:20:15 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:15 volumio volumio[1244]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 09:20:15 volumio volumio[1244]: info: ControllerMpd::pushState Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Mar 25 09:20:15 volumio volumio[1244]: info: CoreStateMachine::pushState Mar 25 09:20:15 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Mar 25 09:20:15 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:15 volumio volumio[1244]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 09:20:15 volumio volumio[1244]: info: ControllerMpd::pushState Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Mar 25 09:20:15 volumio volumio[1244]: info: CoreStateMachine::pushState Mar 25 09:20:15 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Mar 25 09:20:15 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:15 volumio volumio[1244]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 09:20:15 volumio volumio[1244]: info: ControllerMpd::pushState Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Mar 25 09:20:15 volumio volumio[1244]: info: CoreStateMachine::pushState Mar 25 09:20:15 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Mar 25 09:20:15 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:15 volumio volumio[1244]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 09:20:15 volumio volumio[1244]: info: ControllerMpd::pushState Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Mar 25 09:20:15 volumio volumio[1244]: info: CoreStateMachine::pushState Mar 25 09:20:15 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Mar 25 09:20:15 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:15 volumio volumio[1244]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 09:20:15 volumio volumio[1244]: info: ControllerMpd::pushState Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Mar 25 09:20:15 volumio volumio[1244]: info: CoreStateMachine::pushState Mar 25 09:20:15 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Mar 25 09:20:15 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:15 volumio volumio[1244]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 09:20:15 volumio volumio[1244]: info: ControllerMpd::pushState Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Mar 25 09:20:15 volumio volumio[1244]: info: CoreStateMachine::pushState Mar 25 09:20:15 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:15 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Mar 25 09:20:15 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:15 volumio volumio[1244]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 09:20:15 volumio volumio[1244]: info: ------------------------------ 1259ms Mar 25 09:20:15 volumio volumio[1244]: info: ------------------------------ 1259ms Mar 25 09:20:15 volumio volumio[1244]: info: ------------------------------ 1258ms Mar 25 09:20:15 volumio volumio[1244]: info: ------------------------------ 1215ms Mar 25 09:20:15 volumio volumio[1244]: info: ------------------------------ 1215ms Mar 25 09:20:15 volumio volumio[1244]: info: ------------------------------ 1212ms Mar 25 09:20:15 volumio volumio[1244]: info: ------------------------------ 1212ms Mar 25 09:20:15 volumio volumio[1244]: info: ------------------------------ 1211ms Mar 25 09:20:15 volumio volumio[1244]: info: ------------------------------ 1211ms Mar 25 09:20:16 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 549 milliseconds Mar 25 09:20:16 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Mar 25 09:20:16 volumio volumio[1244]: info: ControllerMpd::pushState Mar 25 09:20:16 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Mar 25 09:20:16 volumio volumio[1244]: info: CoreStateMachine::pushState Mar 25 09:20:16 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:16 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Mar 25 09:20:16 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:20:16 volumio volumio[1244]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 09:20:16 volumio volumio[1244]: info: ------------------------------ 1433ms Mar 25 09:20:20 volumio wpa_supplicant[1209]: RRM: Ignoring radio measurement request: Not RRM network Mar 25 09:20:21 volumio wpa_supplicant[1209]: RRM: Ignoring radio measurement request: Not RRM network Mar 25 09:20:23 volumio wpa_supplicant[1209]: RRM: Ignoring radio measurement request: Not RRM network Mar 25 09:20:27 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 25 09:20:27 volumio volumio[1244]: info: CURURI: music-library Mar 25 09:20:27 volumio volumio[1244]: info: Preload queue cleared Mar 25 09:20:33 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 25 09:20:33 volumio volumio[1244]: info: CURURI: music-library/USB Mar 25 09:20:33 volumio volumio[1244]: info: Preload queue cleared Mar 25 09:20:35 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 25 09:20:35 volumio volumio[1244]: info: CURURI: music-library/USB/7C41-4E01 Mar 25 09:20:35 volumio volumio[1244]: error: Failed LSINFO: null Mar 25 09:20:35 volumio volumio[1244]: info: Preload queue cleared Mar 25 09:20:37 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 25 09:20:37 volumio volumio[1244]: info: CURURI: music-library/USB/623E-BC36 Mar 25 09:20:37 volumio volumio[1244]: error: Failed LSINFO: null Mar 25 09:20:37 volumio volumio[1244]: info: Preload queue cleared Mar 25 09:20:40 volumio wpa_supplicant[1209]: RRM: Ignoring radio measurement request: Not RRM network Mar 25 09:20:41 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 25 09:20:41 volumio volumio[1244]: info: CURURI: music-library/NAS Mar 25 09:20:41 volumio volumio[1244]: error: Failed LSINFO: null Mar 25 09:20:41 volumio volumio[1244]: info: Preload queue cleared Mar 25 09:20:42 volumio wpa_supplicant[1209]: RRM: Ignoring radio measurement request: Not RRM network Mar 25 09:20:43 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 25 09:20:43 volumio volumio[1244]: info: CURURI: music-library/INTERNAL Mar 25 09:20:43 volumio volumio[1244]: error: Failed LSINFO: null Mar 25 09:20:43 volumio volumio[1244]: info: Preload queue cleared Mar 25 09:20:44 volumio wpa_supplicant[1209]: RRM: Ignoring radio measurement request: Not RRM network Mar 25 09:20:57 volumio kernel: usb 1-1.3: USB disconnect, device number 3 Mar 25 09:20:58 volumio volumio[1244]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-id/usb-USB_SanDisk_3.2Gen1_0101f5abca748c7d063b505e1fcef8994dd92caa9c3a92be044686dc7919db868bc0000000000000000000006695603d001508009155810750aa1d8c-0:0-part1 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3:1.0-scsi-0:0:0:0-part1 /dev/disk/by-uuid/7C41-4E01","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"26","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"7C41-4E01","ID_FS_UUID_ENC":"7C41-4E01","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"SanDisk_3.2Gen1","ID_MODEL_ENC":"\\x20SanDisk\\x203.2Gen1","ID_MODEL_ID":"5591","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"32","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"120176608","ID_PART_ENTRY_TYPE":"0xc","ID_PART_TABLE_TYPE":"dos","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_3_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"USB_SanDisk_3.2Gen1_0101f5abca748c7d063b505e1fcef8994dd92caa9c3a92be044686dc7919db868bc0000000000000000000006695603d001508009155810750aa1d8c-0:0","ID_SERIAL_SHORT":"0101f5abca748c7d063b505e1fcef8994dd92caa9c3a92be044686dc7919db868bc0000000000000000000006695603d001508009155810750aa1d8c","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INSTANCE":"0:0","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_USB_MODEL":"SanDisk_3.2Gen1","ID_USB_MODEL_ENC":"\\x20SanDisk\\x203.2Gen1","ID_USB_MODEL_ID":"5591","ID_USB_REVISION":"1.00","ID_USB_SERIAL":"USB_SanDisk_3.2Gen1_0101f5abca748c7d063b505e1fcef8994dd92caa9c3a92be044686dc7919db868bc0000000000000000000006695603d001508009155810750aa1d8c-0:0","ID_USB_SERIAL_SHORT":"0101f5abca748c7d063b505e1fcef8994dd92caa9c3a92be044686dc7919db868bc0000000000000000000006695603d001508009155810750aa1d8c","ID_USB_TYPE":"disk","ID_USB_VENDOR":"USB","ID_USB_VENDOR_ENC":"\\x20USB\\x20\\x20\\x20\\x20","ID_USB_VENDOR_ID":"0781","ID_VENDOR":"USB","ID_VENDOR_ENC":"\\x20USB\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"0781","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2757","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3861288"} Mar 25 09:20:58 volumio sudo[2447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Mar 25 09:20:58 volumio sudo[2447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 09:20:58 volumio sudo[2447]: pam_unix(sudo:session): session closed for user root Mar 25 09:20:58 volumio volumio[1244]: umount: /dev/sda1: no mount point specified. Mar 25 09:20:58 volumio volumio[1244]: error: Failed to umount 7C41-4E01: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sda1" Mar 25 09:20:58 volumio volumio[1244]: umount: /dev/sda1: no mount point specified. Mar 25 09:21:00 volumio wpa_supplicant[1209]: RRM: Ignoring radio measurement request: Not RRM network Mar 25 09:21:01 volumio kernel: usb 1-1.4: new high-speed USB device number 4 using xhci_hcd Mar 25 09:21:01 volumio kernel: usb 1-1.4: New USB device found, idVendor=0781, idProduct=5591, bcdDevice= 1.00 Mar 25 09:21:01 volumio kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Mar 25 09:21:01 volumio kernel: usb 1-1.4: Product: SanDisk 3.2Gen1 Mar 25 09:21:01 volumio kernel: usb 1-1.4: Manufacturer: USB Mar 25 09:21:01 volumio kernel: usb 1-1.4: SerialNumber: 0101f5abca748c7d063b505e1fcef8994dd92caa9c3a92be044686dc7919db868bc0000000000000000000006695603d001508009155810750aa1d8c Mar 25 09:21:01 volumio kernel: usb-storage 1-1.4:1.0: USB Mass Storage device detected Mar 25 09:21:01 volumio kernel: scsi host0: usb-storage 1-1.4:1.0 Mar 25 09:21:02 volumio wpa_supplicant[1209]: RRM: Ignoring radio measurement request: Not RRM network Mar 25 09:21:02 volumio kernel: scsi 0:0:0:0: Direct-Access USB SanDisk 3.2Gen1 1.00 PQ: 0 ANSI: 6 Mar 25 09:21:02 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Mar 25 09:21:02 volumio kernel: sd 0:0:0:0: [sda] 120176640 512-byte logical blocks: (61.5 GB/57.3 GiB) Mar 25 09:21:02 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Mar 25 09:21:02 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 Mar 25 09:21:02 volumio kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Mar 25 09:21:02 volumio kernel: sda: sda1 Mar 25 09:21:02 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Mar 25 09:21:02 volumio volumio[1244]: info: Mounting Device 7C41-4E01 Mar 25 09:21:02 volumio sudo[2478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/7C41-4E01 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Mar 25 09:21:02 volumio sudo[2478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 09:21:02 volumio sudo[2478]: pam_unix(sudo:session): session closed for user root Mar 25 09:21:02 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Mar 25 09:21:02 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Mar 25 09:21:03 volumio volumio[1244]: info: Scanning removed location : "USB/7C41-4E01" Mar 25 09:21:03 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:21:03 volumio volumio[1244]: info: Mar 25 09:21:03 volumio volumio[1244]: ---------------------------- MPD announces state update: update Mar 25 09:21:03 volumio volumio[1244]: info: ControllerMpd::getState Mar 25 09:21:03 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:21:03 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:21:03 volumio volumio[1244]: info: Mar 25 09:21:03 volumio volumio[1244]: ---------------------------- MPD announces state update: update Mar 25 09:21:03 volumio volumio[1244]: info: ControllerMpd::getState Mar 25 09:21:03 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:21:03 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:21:03 volumio volumio[1244]: info: Mar 25 09:21:03 volumio volumio[1244]: ---------------------------- MPD announces state update: update Mar 25 09:21:03 volumio volumio[1244]: info: ControllerMpd::getState Mar 25 09:21:03 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:21:03 volumio volumio[1244]: info: sendMpdCommand status took 4 milliseconds Mar 25 09:21:03 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:21:03 volumio volumio[1244]: info: Command Router : Notfying DB Updatetrue Mar 25 09:21:03 volumio volumio[1244]: info: sendMpdCommand status took 65 milliseconds Mar 25 09:21:03 volumio volumio[1244]: info: sendMpdCommand status took 64 milliseconds Mar 25 09:21:03 volumio volumio[1244]: info: sendMpdCommand status took 65 milliseconds Mar 25 09:21:03 volumio volumio[1244]: info: sendMpdCommand status took 64 milliseconds Mar 25 09:21:03 volumio volumio[1244]: info: sendMpdCommand status took 64 milliseconds Mar 25 09:21:03 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:21:03 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 09:21:03 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:21:03 volumio volumio[1244]: info: Command Router : Notfying DB Updatetrue Mar 25 09:21:03 volumio volumio[1244]: info: CoreCommandRouter::Close All Modals sent Mar 25 09:21:03 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:21:03 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 09:21:03 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:21:03 volumio volumio[1244]: info: Command Router : Notfying DB Updatetrue Mar 25 09:21:03 volumio volumio[1244]: info: CoreCommandRouter::Close All Modals sent Mar 25 09:21:03 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:21:03 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 09:21:03 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 45 milliseconds Mar 25 09:21:03 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 30 milliseconds Mar 25 09:21:03 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 25 09:21:03 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Mar 25 09:21:03 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Mar 25 09:21:03 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Mar 25 09:21:03 volumio volumio[1244]: info: ControllerMpd::pushState Mar 25 09:21:03 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Mar 25 09:21:03 volumio volumio[1244]: info: CoreStateMachine::pushState Mar 25 09:21:03 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:21:03 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Mar 25 09:21:03 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:21:03 volumio volumio[1244]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 09:21:03 volumio volumio[1244]: info: ControllerMpd::pushState Mar 25 09:21:03 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Mar 25 09:21:03 volumio volumio[1244]: info: CoreStateMachine::pushState Mar 25 09:21:03 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:21:03 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Mar 25 09:21:03 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:21:03 volumio volumio[1244]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 09:21:03 volumio volumio[1244]: info: ControllerMpd::pushState Mar 25 09:21:03 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Mar 25 09:21:03 volumio volumio[1244]: info: CoreStateMachine::pushState Mar 25 09:21:03 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:21:03 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Mar 25 09:21:03 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:21:03 volumio volumio[1244]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 09:21:03 volumio volumio[1244]: info: ------------------------------ 135ms Mar 25 09:21:03 volumio volumio[1244]: info: ------------------------------ 134ms Mar 25 09:21:03 volumio volumio[1244]: info: ------------------------------ 133ms Mar 25 09:21:04 volumio wpa_supplicant[1209]: RRM: Ignoring radio measurement request: Not RRM network Mar 25 09:21:05 volumio volumio[1244]: info: MPD Database updated - AlbumList cache refreshed Mar 25 09:21:05 volumio volumio[1244]: info: Mar 25 09:21:05 volumio volumio[1244]: ---------------------------- MPD announces state update: database Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:21:05 volumio volumio[1244]: info: Mar 25 09:21:05 volumio volumio[1244]: ---------------------------- MPD announces state update: update Mar 25 09:21:05 volumio volumio[1244]: info: ControllerMpd::getState Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:21:05 volumio volumio[1244]: info: ControllerMpd::getState Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:21:05 volumio volumio[1244]: info: MPD Database updated - AlbumList cache refreshed Mar 25 09:21:05 volumio volumio[1244]: info: Mar 25 09:21:05 volumio volumio[1244]: ---------------------------- MPD announces state update: database Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:21:05 volumio volumio[1244]: info: Mar 25 09:21:05 volumio volumio[1244]: ---------------------------- MPD announces state update: update Mar 25 09:21:05 volumio volumio[1244]: info: ControllerMpd::getState Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:21:05 volumio volumio[1244]: info: ControllerMpd::getState Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:21:05 volumio volumio[1244]: info: MPD Database updated - AlbumList cache refreshed Mar 25 09:21:05 volumio volumio[1244]: info: Mar 25 09:21:05 volumio volumio[1244]: ---------------------------- MPD announces state update: database Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:21:05 volumio volumio[1244]: info: Mar 25 09:21:05 volumio volumio[1244]: ---------------------------- MPD announces state update: update Mar 25 09:21:05 volumio volumio[1244]: info: ControllerMpd::getState Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:21:05 volumio volumio[1244]: info: ControllerMpd::getState Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Mar 25 09:21:05 volumio volumio[1244]: info: sendMpdCommand status took 9 milliseconds Mar 25 09:21:05 volumio volumio[1244]: info: sendMpdCommand status took 9 milliseconds Mar 25 09:21:05 volumio volumio[1244]: info: sendMpdCommand status took 8 milliseconds Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:21:05 volumio volumio[1244]: info: Command Router : Notfying DB Updatefalse Mar 25 09:21:05 volumio volumio[1244]: info: CoreCommandRouter::Close All Modals sent Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 09:21:05 volumio volumio[1244]: info: sendMpdCommand status took 67 milliseconds Mar 25 09:21:05 volumio volumio[1244]: info: sendMpdCommand status took 67 milliseconds Mar 25 09:21:05 volumio volumio[1244]: info: sendMpdCommand status took 66 milliseconds Mar 25 09:21:05 volumio volumio[1244]: info: sendMpdCommand status took 66 milliseconds Mar 25 09:21:05 volumio volumio[1244]: info: sendMpdCommand status took 65 milliseconds Mar 25 09:21:05 volumio volumio[1244]: info: sendMpdCommand status took 65 milliseconds Mar 25 09:21:05 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 25 09:21:05 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:21:05 volumio volumio[1244]: info: Command Router : Notfying DB Updatefalse Mar 25 09:21:05 volumio volumio[1244]: info: CoreCommandRouter::Close All Modals sent Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:21:05 volumio volumio[1244]: info: Command Router : Notfying DB Updatefalse Mar 25 09:21:05 volumio volumio[1244]: info: CoreCommandRouter::Close All Modals sent Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::parseState Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Mar 25 09:21:05 volumio volumio[1244]: info: ControllerMpd::pushState Mar 25 09:21:05 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Mar 25 09:21:05 volumio volumio[1244]: info: CoreStateMachine::pushState Mar 25 09:21:05 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:21:05 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Mar 25 09:21:05 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:21:05 volumio volumio[1244]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 09:21:05 volumio volumio[1244]: info: ControllerMpd::pushState Mar 25 09:21:05 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Mar 25 09:21:05 volumio volumio[1244]: info: CoreStateMachine::pushState Mar 25 09:21:05 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:21:05 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Mar 25 09:21:05 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:21:05 volumio volumio[1244]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 09:21:05 volumio volumio[1244]: info: ------------------------------ 114ms Mar 25 09:21:05 volumio volumio[1244]: info: ------------------------------ 114ms Mar 25 09:21:05 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 39 milliseconds Mar 25 09:21:05 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 39 milliseconds Mar 25 09:21:05 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 29 milliseconds Mar 25 09:21:05 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 28 milliseconds Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Mar 25 09:21:05 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Mar 25 09:21:05 volumio volumio[1244]: info: ControllerMpd::pushState Mar 25 09:21:05 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Mar 25 09:21:05 volumio volumio[1244]: info: CoreStateMachine::pushState Mar 25 09:21:05 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:21:05 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Mar 25 09:21:05 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:21:05 volumio volumio[1244]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 09:21:05 volumio volumio[1244]: info: ControllerMpd::pushState Mar 25 09:21:05 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Mar 25 09:21:05 volumio volumio[1244]: info: CoreStateMachine::pushState Mar 25 09:21:05 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:21:05 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Mar 25 09:21:05 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:21:05 volumio volumio[1244]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 09:21:05 volumio volumio[1244]: info: ControllerMpd::pushState Mar 25 09:21:05 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Mar 25 09:21:05 volumio volumio[1244]: info: CoreStateMachine::pushState Mar 25 09:21:05 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:21:05 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Mar 25 09:21:05 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:21:05 volumio volumio[1244]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 09:21:05 volumio volumio[1244]: info: ControllerMpd::pushState Mar 25 09:21:05 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Mar 25 09:21:05 volumio volumio[1244]: info: CoreStateMachine::pushState Mar 25 09:21:05 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:21:05 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Mar 25 09:21:05 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Mar 25 09:21:05 volumio volumio[1244]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 25 09:21:05 volumio volumio[1244]: info: ------------------------------ 148ms Mar 25 09:21:05 volumio volumio[1244]: info: ------------------------------ 148ms Mar 25 09:21:05 volumio volumio[1244]: info: ------------------------------ 146ms Mar 25 09:21:05 volumio volumio[1244]: info: ------------------------------ 146ms Mar 25 09:21:11 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 25 09:21:11 volumio volumio[1244]: info: CURURI: music-library Mar 25 09:21:11 volumio volumio[1244]: info: Preload queue cleared Mar 25 09:21:12 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 25 09:21:12 volumio volumio[1244]: info: CURURI: music-library/USB Mar 25 09:21:13 volumio volumio[1244]: info: Preload queue cleared Mar 25 09:21:13 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 25 09:21:13 volumio volumio[1244]: info: CURURI: music-library/USB/7C41-4E01 Mar 25 09:21:14 volumio volumio[1244]: info: Preload queue cleared Mar 25 09:21:17 volumio volumio[1244]: info: Preload queue cleared Mar 25 09:21:17 volumio volumio[1244]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 09:21:17 volumio volumio[1244]: info: CoreStateMachine::ClearQueue Mar 25 09:21:17 volumio volumio[1244]: info: CoreStateMachine::stop Mar 25 09:21:17 volumio volumio[1244]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 09:21:17 volumio volumio[1244]: info: CorePlayQueue::clearPlayQueue Mar 25 09:21:17 volumio volumio[1244]: info: CorePlayQueue::saveQueue Mar 25 09:21:17 volumio volumio[1244]: info: CoreCommandRouter::volumioPushQueue Mar 25 09:21:17 volumio volumio[1244]: info: CoreStateMachine::addQueueItems Mar 25 09:21:17 volumio volumio[1244]: info: CorePlayQueue::addQueueItems Mar 25 09:21:17 volumio volumio[1244]: info: Preload queue cleared Mar 25 09:21:17 volumio volumio[1244]: info: Adding Item to queue: music-library/USB/7C41-4E01/CD圣经上榜天碟《竹乐-春风翠竹》24K金碟[WAV+CUE] Mar 25 09:21:17 volumio volumio[1244]: info: Exploding uri music-library/USB/7C41-4E01/CD圣经上榜天碟《竹乐-春风翠竹》24K金碟[WAV+CUE] in service mpd Mar 25 09:21:17 volumio volumio[1244]: info: ALBUMART /albumart?cacheid=131&web=%C8%BA%3F%3F/%3F%3F%3F%3F-%3F%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F7C41-4E01%2FCD%E5%9C%A3%E7%BB%8F%E4%B8%8A%E6%A6%9C%E5%A4%A9%E7%A2%9F%E3%80%8A%E7%AB%B9%E4%B9%90-%E6%98%A5%E9%A3%8E%E7%BF%A0%E7%AB%B9%E3%80%8B24K%E9%87%91%E7%A2%9F%5BWAV%2BCUE%5D%2FCDImage.cue&metadata=false Mar 25 09:21:17 volumio volumio[1244]: info: URI /mnt/USB/7C41-4E01/CD圣经上榜天碟《竹乐-春风翠竹》24K金碟[WAV+CUE]/CDImage.cue Mar 25 09:21:17 volumio volumio[1244]: info: ALBUMART /albumart?cacheid=131&web=%C8%BA%3F%3F/%3F%3F%3F%3F-%3F%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F7C41-4E01%2FCD%E5%9C%A3%E7%BB%8F%E4%B8%8A%E6%A6%9C%E5%A4%A9%E7%A2%9F%E3%80%8A%E7%AB%B9%E4%B9%90-%E6%98%A5%E9%A3%8E%E7%BF%A0%E7%AB%B9%E3%80%8B24K%E9%87%91%E7%A2%9F%5BWAV%2BCUE%5D%2FCDImage.cue&metadata=false Mar 25 09:21:17 volumio volumio[1244]: info: URI /mnt/USB/7C41-4E01/CD圣经上榜天碟《竹乐-春风翠竹》24K金碟[WAV+CUE]/CDImage.cue Mar 25 09:21:17 volumio volumio[1244]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 09:21:17 volumio volumio[1244]: Error: Unable to resolve or reject the same promise twice Mar 25 09:21:17 volumio volumio[1244]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 25 09:21:17 volumio volumio[1244]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Mar 25 09:21:17 volumio volumio[1244]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Mar 25 09:21:17 volumio volumio[1244]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Mar 25 09:21:17 volumio volumio[1244]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 25 09:21:17 volumio volumio[1244]: at Socket.emit (node:events:514:28) Mar 25 09:21:17 volumio volumio[1244]: at addChunk (node:internal/streams/readable:343:12) Mar 25 09:21:17 volumio volumio[1244]: at readableAddChunk (node:internal/streams/readable:312:11) Mar 25 09:21:17 volumio volumio[1244]: at Readable.push (node:internal/streams/readable:253:10) Mar 25 09:21:17 volumio volumio[1244]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Mar 25 09:21:17 volumio volumio[1244]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 09:21:18 volumio sudo[2536]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-25 09:20' Mar 25 09:21:18 volumio sudo[2536]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"