-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Tue 2025-06-17 14:21:30 CST. -- Jun 17 14:20:00 volumio sudo[2143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 17 14:20:00 volumio sudo[2143]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 14:20:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 17 14:20:00 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 17 14:20:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 17 14:20:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 17 14:20:00 volumio sudo[2143]: pam_unix(sudo:session): session closed for user root Jun 17 14:20:00 volumio volumio[759]: info: Shairport-Sync Started Jun 17 14:20:00 volumio sudo[2149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 17 14:20:00 volumio sudo[2149]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 14:20:00 volumio sudo[2149]: pam_unix(sudo:session): session closed for user root Jun 17 14:20:00 volumio sudo[2152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 17 14:20:00 volumio sudo[2152]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 14:20:00 volumio sudo[2152]: pam_unix(sudo:session): session closed for user root Jun 17 14:20:00 volumio volumio[759]: verbose: New Socket.io Connection to 192.168.31.144 from 192.168.31.186 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 6 Jun 17 14:20:00 volumio systemd[1]: mpd.service: Succeeded. Jun 17 14:20:00 volumio systemd[1]: Stopped Music Player Daemon. Jun 17 14:20:00 volumio systemd[1]: Starting Music Player Daemon... Jun 17 14:20:00 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 17 14:20:00 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 17 14:20:00 volumio volumio[759]: info: CoreCommandRouter::volumioGetVisibleSources Jun 17 14:20:00 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 14:20:00 volumio volumio[759]: info: CoreCommandRouter::volumioGetState Jun 17 14:20:00 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:20:00 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 17 14:20:00 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 17 14:20:00 volumio volumio[759]: info: Received Get System Info Jun 17 14:20:00 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 17 14:20:00 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 17 14:20:00 volumio volumio[759]: info: Discovery: Getting this device information Jun 17 14:20:00 volumio volumio[759]: info: CoreCommandRouter::volumioGetState Jun 17 14:20:00 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:20:00 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 17 14:20:00 volumio volumio[759]: info: CoreCommandRouter::volumioGetState Jun 17 14:20:00 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:20:00 volumio volumio[759]: info: Listing playlists Jun 17 14:20:00 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 17 14:20:00 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 17 14:20:00 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 17 14:20:01 volumio mpd[2155]: Jun 17 14:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 17 14:20:01 volumio volumio[759]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/DSD Jun 17 14:20:02 volumio systemd[1]: Started Music Player Daemon. Jun 17 14:20:02 volumio sudo[2133]: pam_unix(sudo:session): session closed for user root Jun 17 14:20:02 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 17 14:20:02 volumio volumio[759]: info: Received Get System Info Jun 17 14:20:02 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 17 14:20:02 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 17 14:20:02 volumio volumio[759]: info: Discovery: Getting this device information Jun 17 14:20:02 volumio volumio[759]: info: CoreCommandRouter::volumioGetState Jun 17 14:20:02 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:20:02 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 17 14:20:02 volumio volumio[759]: error: updateQueue error: null Jun 17 14:20:02 volumio volumio[759]: info: Scanning removed location : "USB/DSD" Jun 17 14:20:02 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Jun 17 14:20:02 volumio volumio[759]: info: Jun 17 14:20:02 volumio volumio[759]: ---------------------------- MPD announces state update: update Jun 17 14:20:02 volumio volumio[759]: info: ControllerMpd::getState Jun 17 14:20:02 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Jun 17 14:20:02 volumio volumio[759]: info: sendMpdCommand status took 2 milliseconds Jun 17 14:20:02 volumio volumio[759]: info: sendMpdCommand status took 1 milliseconds Jun 17 14:20:02 volumio volumio[759]: verbose: ControllerMpd::parseState Jun 17 14:20:02 volumio volumio[759]: info: Command Router : Notfying DB Updatetrue Jun 17 14:20:02 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Jun 17 14:20:02 volumio volumio[759]: verbose: ControllerMpd::parseState Jun 17 14:20:02 volumio volumio[759]: info: ControllerMpd::pushState Jun 17 14:20:02 volumio volumio[759]: info: CoreCommandRouter::servicePushState Jun 17 14:20:02 volumio volumio[759]: info: CoreStateMachine::pushState Jun 17 14:20:02 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:20:02 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 14:20:02 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Jun 17 14:20:02 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:20:02 volumio volumio[759]: 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} Jun 17 14:20:02 volumio volumio[759]: verbose: CURRENT POSITION 1 Jun 17 14:20:02 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Jun 17 14:20:02 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus pause Jun 17 14:20:02 volumio volumio[759]: info: CoreStateMachine::pushState Jun 17 14:20:02 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:20:02 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Jun 17 14:20:02 volumio volumio[759]: info: ------------------------------ 69ms Jun 17 14:20:03 volumio volumio[759]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 17 14:20:03 volumio volumio[759]: info: CoreStateMachine::getcurrentVolume Jun 17 14:20:03 volumio volumio[759]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 14:20:03 volumio volumio[759]: info: VolumeController:: Volume=100 Mute =false Jun 17 14:20:03 volumio volumio[759]: info: CoreStateMachine::pushState Jun 17 14:20:03 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:20:03 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 14:20:03 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Jun 17 14:20:03 volumio volumio[759]: info: CoreStateMachine::updateTrackBlock Jun 17 14:20:03 volumio volumio[759]: info: CorePlayQueue::getTrackBlock Jun 17 14:20:03 volumio volumio[759]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 14:20:03 volumio volumio[759]: info: VolumeController:: Volume=100 Mute =false Jun 17 14:20:03 volumio volumio[759]: info: CoreStateMachine::pushState Jun 17 14:20:03 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:20:03 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Jun 17 14:20:03 volumio volumio[759]: info: CoreCommandRouter::volumioGetQueue Jun 17 14:20:03 volumio volumio[759]: info: CoreStateMachine::getQueue Jun 17 14:20:03 volumio volumio[759]: info: CorePlayQueue::getQueue Jun 17 14:20:06 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 17 14:20:06 volumio volumio[759]: info: CURURI: music-library Jun 17 14:20:06 volumio volumio[759]: info: Preload queue cleared Jun 17 14:20:08 volumio volumio[759]: info: MPD Database updated - AlbumList cache refreshed Jun 17 14:20:08 volumio volumio[759]: info: Jun 17 14:20:08 volumio volumio[759]: ---------------------------- MPD announces state update: database Jun 17 14:20:08 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Jun 17 14:20:08 volumio volumio[759]: info: Jun 17 14:20:08 volumio volumio[759]: ---------------------------- MPD announces state update: update Jun 17 14:20:08 volumio volumio[759]: info: ControllerMpd::getState Jun 17 14:20:08 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Jun 17 14:20:08 volumio volumio[759]: info: ControllerMpd::getState Jun 17 14:20:08 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Jun 17 14:20:10 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 17 14:20:10 volumio volumio[759]: info: CURURI: music-library/USB Jun 17 14:20:31 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 17 14:20:31 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 17 14:20:31 volumio volumio[759]: info: Discovery: Getting this device information Jun 17 14:20:31 volumio volumio[759]: info: CoreCommandRouter::volumioGetState Jun 17 14:20:31 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:20:31 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 17 14:20:34 volumio ntpd[669]: 193.182.111.142 local addr 192.168.31.144 -> Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:39 volumio volumio[759]: Cannot compose Albumart path Jun 17 14:20:41 volumio volumio[759]: info: sendMpdCommand status took 33214 milliseconds Jun 17 14:20:41 volumio volumio[759]: info: sendMpdCommand status took 33219 milliseconds Jun 17 14:20:41 volumio volumio[759]: info: sendMpdCommand status took 33219 milliseconds Jun 17 14:20:41 volumio volumio[759]: verbose: ControllerMpd::parseState Jun 17 14:20:41 volumio volumio[759]: info: Command Router : Notfying DB Updatefalse Jun 17 14:20:41 volumio volumio[759]: info: CoreCommandRouter::Close All Modals sent Jun 17 14:20:41 volumio volumio[759]: verbose: ControllerMpd::parseState Jun 17 14:20:41 volumio volumio[759]: verbose: ControllerMpd::parseState Jun 17 14:20:41 volumio volumio[759]: info: ControllerMpd::pushState Jun 17 14:20:41 volumio volumio[759]: info: CoreCommandRouter::servicePushState Jun 17 14:20:41 volumio volumio[759]: info: CoreStateMachine::pushState Jun 17 14:20:41 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:20:41 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 14:20:41 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Jun 17 14:20:41 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:20:41 volumio volumio[759]: 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} Jun 17 14:20:41 volumio volumio[759]: verbose: CURRENT POSITION 1 Jun 17 14:20:41 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Jun 17 14:20:41 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus pause Jun 17 14:20:41 volumio volumio[759]: info: CoreStateMachine::pushState Jun 17 14:20:41 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:20:41 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 14:20:41 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Jun 17 14:20:41 volumio volumio[759]: info: ControllerMpd::pushState Jun 17 14:20:41 volumio volumio[759]: info: CoreCommandRouter::servicePushState Jun 17 14:20:41 volumio volumio[759]: info: CoreStateMachine::pushState Jun 17 14:20:41 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:20:41 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Jun 17 14:20:41 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:20:41 volumio volumio[759]: 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} Jun 17 14:20:41 volumio volumio[759]: verbose: CURRENT POSITION 1 Jun 17 14:20:41 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Jun 17 14:20:41 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus pause Jun 17 14:20:41 volumio volumio[759]: info: CoreStateMachine::pushState Jun 17 14:20:41 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:20:41 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Jun 17 14:20:41 volumio volumio[759]: info: ------------------------------ 33330ms Jun 17 14:20:41 volumio volumio[759]: info: ------------------------------ 33331ms Jun 17 14:20:41 volumio volumio[759]: info: Preload queue cleared Jun 17 14:20:57 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 17 14:20:57 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 17 14:20:57 volumio volumio[759]: info: Discovery: Getting this device information Jun 17 14:20:57 volumio volumio[759]: info: CoreCommandRouter::volumioGetState Jun 17 14:20:57 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:20:57 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 17 14:21:21 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:21:21 volumio volumio[759]: info: CorePlayQueue::getTrack 2 Jun 17 14:21:21 volumio volumio[759]: info: Prefetching next song Jun 17 14:21:21 volumio volumio[759]: info: DOING PREFETCH IN MPD Jun 17 14:21:21 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand add "USB/DSD/DSD 64 Queen - Jazz/04 - Queen - Bicycle Race.dff" Jun 17 14:21:21 volumio volumio[759]: info: Jun 17 14:21:21 volumio volumio[759]: ---------------------------- MPD announces system playlist update Jun 17 14:21:21 volumio volumio[759]: info: Ignoring MPD Status Update Jun 17 14:21:21 volumio volumio[759]: info: sendMpdCommand add "USB/DSD/DSD 64 Queen - Jazz/04 - Queen - Bicycle Race.dff" took 4 milliseconds Jun 17 14:21:21 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 17 14:21:21 volumio volumio[759]: info: Jun 17 14:21:21 volumio volumio[759]: ---------------------------- MPD announces state update: options Jun 17 14:21:21 volumio volumio[759]: info: ------------------------------ 4ms Jun 17 14:21:21 volumio volumio[759]: info: sendMpdCommand consume 1 took 2 milliseconds Jun 17 14:21:21 volumio volumio[759]: info: ControllerMpd::getState Jun 17 14:21:21 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand status Jun 17 14:21:21 volumio volumio[759]: info: sendMpdCommand status took 1 milliseconds Jun 17 14:21:21 volumio volumio[759]: verbose: ControllerMpd::parseState Jun 17 14:21:21 volumio volumio[759]: info: ControllerMpd::pushState Jun 17 14:21:21 volumio volumio[759]: info: CoreCommandRouter::servicePushState Jun 17 14:21:21 volumio volumio[759]: info: CoreStateMachine::pushState Jun 17 14:21:21 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:21:21 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 14:21:21 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Jun 17 14:21:21 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:21:21 volumio volumio[759]: 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} Jun 17 14:21:21 volumio volumio[759]: verbose: CURRENT POSITION 1 Jun 17 14:21:21 volumio volumio[759]: info: CoreStateMachine::syncState stateService stop Jun 17 14:21:21 volumio volumio[759]: info: CoreStateMachine::syncState currentStatus pause Jun 17 14:21:21 volumio volumio[759]: info: CoreStateMachine::pushState Jun 17 14:21:21 volumio volumio[759]: info: CorePlayQueue::getTrack 1 Jun 17 14:21:21 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Jun 17 14:21:21 volumio volumio[759]: info: ------------------------------ 17ms Jun 17 14:21:25 volumio volumio[759]: info: CoreStateMachine::startPlaybackTimer Jun 17 14:21:25 volumio volumio[759]: info: CorePlayQueue::getTrack 2 Jun 17 14:21:26 volumio volumio[759]: info: CoreStateMachine::pushState Jun 17 14:21:26 volumio volumio[759]: info: CorePlayQueue::getTrack 2 Jun 17 14:21:26 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 14:21:26 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Jun 17 14:21:27 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 17 14:21:27 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 17 14:21:27 volumio volumio[759]: info: Discovery: Getting this device information Jun 17 14:21:27 volumio volumio[759]: info: CoreCommandRouter::volumioGetState Jun 17 14:21:27 volumio volumio[759]: info: CorePlayQueue::getTrack 2 Jun 17 14:21:27 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 17 14:21:28 volumio volumio[759]: info: Preload queue cleared Jun 17 14:21:28 volumio volumio[759]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 17 14:21:28 volumio volumio[759]: info: CoreStateMachine::ClearQueue Jun 17 14:21:28 volumio volumio[759]: info: CoreStateMachine::stop Jun 17 14:21:28 volumio volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 14:21:28 volumio volumio[759]: info: CoreStateMachine::updateTrackBlock Jun 17 14:21:28 volumio volumio[759]: info: CorePlayQueue::getTrackBlock Jun 17 14:21:28 volumio volumio[759]: info: CoreStateMachine::stPlaybackTimer Jun 17 14:21:28 volumio volumio[759]: info: CoreStateMachine::pushState Jun 17 14:21:28 volumio volumio[759]: info: CorePlayQueue::getTrack 2 Jun 17 14:21:28 volumio volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 14:21:28 volumio volumio[759]: info: CoreCommandRouter::volumioPushState Jun 17 14:21:28 volumio volumio[759]: info: CoreStateMachine::serviceStop Jun 17 14:21:28 volumio volumio[759]: info: CorePlayQueue::getTrack 2 Jun 17 14:21:28 volumio volumio[759]: info: CoreCommandRouter::serviceStop Jun 17 14:21:28 volumio volumio[759]: info: ControllerMpd::stop Jun 17 14:21:28 volumio volumio[759]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 14:21:28 volumio volumio[759]: info: CorePlayQueue::clearPlayQueue Jun 17 14:21:28 volumio volumio[759]: info: CorePlayQueue::saveQueue Jun 17 14:21:28 volumio volumio[759]: info: CoreCommandRouter::volumioPushQueue Jun 17 14:21:28 volumio volumio[759]: info: CoreStateMachine::addQueueItems Jun 17 14:21:28 volumio volumio[759]: info: CorePlayQueue::addQueueItems Jun 17 14:21:28 volumio volumio[759]: info: Preload queue cleared Jun 17 14:21:28 volumio volumio[759]: info: Adding Item to queue: music-library/USB/DSD Jun 17 14:21:28 volumio volumio[759]: info: Exploding uri music-library/USB/DSD in service mpd Jun 17 14:21:29 volumio volumio[759]: info: ALBUMART /albumart?cacheid=657&web=//extralarge&path=%2Fmnt%2FUSB%2FDSD%2F%24RECYCLE.BIN%2FS-1-5-21-1479083868-3704999175-3216154275-1001%2F%24R2Y09XE.dff&metadata=false Jun 17 14:21:29 volumio volumio[759]: info: URI /mnt/USB/DSD/$RECYCLE.BIN/S-1-5-21-1479083868-3704999175-3216154275-1001/$R2Y09XE.dff Jun 17 14:21:29 volumio volumio[759]: info: ALBUMART /albumart?cacheid=657&web=/Miles%20Smiles/extralarge&path=%2Fmnt%2FUSB%2FDSD%2F%24RECYCLE.BIN%2FS-1-5-21-1479083868-3704999175-3216154275-1001%2F%24R3V10L9%2FMiles%20Davis%20-%20Miles%20Smiles.cue&metadata=false Jun 17 14:21:29 volumio volumio[759]: info: URI /mnt/USB/DSD/$RECYCLE.BIN/S-1-5-21-1479083868-3704999175-3216154275-1001/$R3V10L9/Miles Davis - Miles Smiles.cue Jun 17 14:21:29 volumio volumio[759]: info: ALBUMART /albumart?cacheid=657&web=/Miles%20Smiles/extralarge&path=%2Fmnt%2FUSB%2FDSD%2F%24RECYCLE.BIN%2FS-1-5-21-1479083868-3704999175-3216154275-1001%2F%24R3V10L9%2FMiles%20Davis%20-%20Miles%20Smiles.cue&metadata=false Jun 17 14:21:29 volumio volumio[759]: info: URI /mnt/USB/DSD/$RECYCLE.BIN/S-1-5-21-1479083868-3704999175-3216154275-1001/$R3V10L9/Miles Davis - Miles Smiles.cue Jun 17 14:21:29 volumio volumio[759]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 14:21:29 volumio volumio[759]: Error: Unable to resolve or reject the same promise twice Jun 17 14:21:29 volumio volumio[759]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jun 17 14:21:29 volumio volumio[759]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Jun 17 14:21:29 volumio volumio[759]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jun 17 14:21:29 volumio volumio[759]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jun 17 14:21:29 volumio volumio[759]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jun 17 14:21:29 volumio volumio[759]: at Socket.emit (events.js:315:20) Jun 17 14:21:29 volumio volumio[759]: at addChunk (internal/streams/readable.js:309:12) Jun 17 14:21:29 volumio volumio[759]: at readableAddChunk (internal/streams/readable.js:280:11) Jun 17 14:21:29 volumio volumio[759]: at Socket.Readable.push (internal/streams/readable.js:223:10) Jun 17 14:21:29 volumio volumio[759]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Jun 17 14:21:29 volumio volumio[759]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 14:21:30 volumio sudo[2260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-17 14:20 Jun 17 14:21:30 volumio sudo[2260]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"