-- Logs begin at Thu 2025-06-12 19:22:26 MSK, end at Thu 2025-06-12 22:10:18 MSK. -- Jun 12 22:09:44 volumio volumio[7509]: verbose: New Socket.io Connection to 192.168.1.2 from 192.168.1.21 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 YaBrowser/25.4.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 12 22:09:44 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 12 22:09:44 volumio volumio[7509]: info: CoreCommandRouter::volumioGetVisibleSources Jun 12 22:09:44 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 22:09:44 volumio volumio[7509]: info: CoreCommandRouter::volumioGetState Jun 12 22:09:44 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:09:44 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 12 22:09:44 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 12 22:09:44 volumio volumio[7509]: info: Received Get System Info Jun 12 22:09:44 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 22:09:44 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 22:09:44 volumio volumio[7509]: info: Discovery: Getting this device information Jun 12 22:09:44 volumio volumio[7509]: info: CoreCommandRouter::volumioGetState Jun 12 22:09:44 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:09:44 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 22:09:44 volumio volumio[7509]: info: CoreCommandRouter::volumioGetState Jun 12 22:09:44 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:09:44 volumio volumio[7509]: info: Listing playlists Jun 12 22:09:44 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 12 22:09:44 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 12 22:09:44 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 12 22:09:45 volumio volumio[7509]: info: CoreCommandRouter::volumioGetState Jun 12 22:09:45 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:09:45 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 12 22:09:46 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 12 22:09:46 volumio volumio[7509]: info: Received Get System Info Jun 12 22:09:46 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 22:09:46 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 22:09:46 volumio volumio[7509]: info: Discovery: Getting this device information Jun 12 22:09:46 volumio volumio[7509]: info: CoreCommandRouter::volumioGetState Jun 12 22:09:46 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:09:46 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 22:09:47 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 12 22:09:47 volumio volumio[7509]: info: Received Get System Info Jun 12 22:09:47 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 22:09:47 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 22:09:47 volumio volumio[7509]: info: Discovery: Getting this device information Jun 12 22:09:47 volumio volumio[7509]: info: CoreCommandRouter::volumioGetState Jun 12 22:09:47 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:09:47 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 22:09:48 volumio volumio[7509]: info: CoreCommandRouter::volumioSeek Jun 12 22:09:48 volumio volumio[7509]: info: CoreStateMachine::seek Jun 12 22:09:48 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:09:48 volumio volumio[7509]: info: TRACKBLOCK {"uri":"mnt/NAS/Nas/450Gb/Test/NAIM - The Hi-Fi Collection/Naim - The Hi-Fi Collection.flac","service":"mpd","name":"Naim - The Hi-Fi Collection.flac","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=478&web=//extralarge&path=%2FNAS%2FNas%2F450Gb%2FTest%2FNAIM%20-%20The%20Hi-Fi%20Collection&metadata=false","duration":3425,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Jun 12 22:09:48 volumio volumio[7509]: info: CoreStateMachine::startPlaybackTimer Jun 12 22:09:48 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:09:48 volumio volumio[7509]: info: ControllerMpd::seek Jun 12 22:09:48 volumio volumio[7509]: info: CoreStateMachine::pushState Jun 12 22:09:48 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:09:48 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 22:09:48 volumio volumio[7509]: info: CoreCommandRouter::volumioPushState Jun 12 22:09:48 volumio volumio[7509]: error: null Jun 12 22:09:48 volumio volumio[7509]: info: Jun 12 22:09:48 volumio volumio[7509]: ---------------------------- MPD announces state update: player Jun 12 22:09:48 volumio volumio[7509]: info: ControllerMpd::getState Jun 12 22:09:48 volumio volumio[7509]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:09:48 volumio volumio[7509]: info: Jun 12 22:09:48 volumio volumio[7509]: ---------------------------- MPD announces state update: player Jun 12 22:09:48 volumio volumio[7509]: info: ControllerMpd::getState Jun 12 22:09:48 volumio volumio[7509]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:09:48 volumio volumio[7509]: info: Jun 12 22:09:48 volumio volumio[7509]: ---------------------------- MPD announces state update: player Jun 12 22:09:48 volumio volumio[7509]: info: ControllerMpd::getState Jun 12 22:09:48 volumio volumio[7509]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:09:48 volumio volumio[7509]: info: sendMpdCommand status took 1 milliseconds Jun 12 22:09:48 volumio volumio[7509]: info: sendMpdCommand status took 1 milliseconds Jun 12 22:09:48 volumio volumio[7509]: info: sendMpdCommand status took 0 milliseconds Jun 12 22:09:48 volumio volumio[7509]: verbose: ControllerMpd::parseState Jun 12 22:09:48 volumio volumio[7509]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 22:09:48 volumio volumio[7509]: verbose: ControllerMpd::parseState Jun 12 22:09:48 volumio volumio[7509]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 22:09:48 volumio volumio[7509]: verbose: ControllerMpd::parseState Jun 12 22:09:48 volumio volumio[7509]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 22:09:48 volumio volumio[7509]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 12 22:09:48 volumio volumio[7509]: verbose: ControllerMpd::parseTrackInfo Jun 12 22:09:48 volumio volumio[7509]: info: ControllerMpd::pushState Jun 12 22:09:48 volumio volumio[7509]: info: CoreCommandRouter::servicePushState Jun 12 22:09:48 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:09:48 volumio volumio[7509]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2477000,"duration":3425,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"832 Kbps","isStreaming":false,"title":"Naim - The Hi-Fi Collection.flac","artist":null,"album":null,"uri":"NAS/Nas/450Gb/Test/NAIM - The Hi-Fi Collection/Naim - The Hi-Fi Collection.flac","trackType":"flac"} Jun 12 22:09:48 volumio volumio[7509]: verbose: CURRENT POSITION 0 Jun 12 22:09:48 volumio volumio[7509]: info: CoreStateMachine::syncState stateService play Jun 12 22:09:48 volumio volumio[7509]: info: CoreStateMachine::syncState currentStatus play Jun 12 22:09:48 volumio volumio[7509]: info: Received an update from plugin. extracting info from payload Jun 12 22:09:48 volumio volumio[7509]: info: CoreStateMachine::pushState Jun 12 22:09:48 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:09:48 volumio volumio[7509]: info: CoreCommandRouter::volumioPushState Jun 12 22:09:48 volumio volumio[7509]: info: CoreStateMachine::pushState Jun 12 22:09:48 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:09:48 volumio volumio[7509]: info: CoreCommandRouter::volumioPushState Jun 12 22:09:48 volumio volumio[7509]: info: ------------------------------ 4ms Jun 12 22:09:48 volumio volumio[7509]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 12 22:09:48 volumio volumio[7509]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 12 22:09:48 volumio volumio[7509]: verbose: ControllerMpd::parseTrackInfo Jun 12 22:09:48 volumio volumio[7509]: info: ControllerMpd::pushState Jun 12 22:09:48 volumio volumio[7509]: info: CoreCommandRouter::servicePushState Jun 12 22:09:48 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:09:48 volumio volumio[7509]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2477000,"duration":3425,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"832 Kbps","isStreaming":false,"title":"Naim - The Hi-Fi Collection.flac","artist":null,"album":null,"uri":"NAS/Nas/450Gb/Test/NAIM - The Hi-Fi Collection/Naim - The Hi-Fi Collection.flac","trackType":"flac"} Jun 12 22:09:48 volumio volumio[7509]: verbose: CURRENT POSITION 0 Jun 12 22:09:48 volumio volumio[7509]: info: CoreStateMachine::syncState stateService play Jun 12 22:09:48 volumio volumio[7509]: info: CoreStateMachine::syncState currentStatus play Jun 12 22:09:48 volumio volumio[7509]: info: Received an update from plugin. extracting info from payload Jun 12 22:09:48 volumio volumio[7509]: info: CoreStateMachine::pushState Jun 12 22:09:48 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:09:48 volumio volumio[7509]: info: CoreCommandRouter::volumioPushState Jun 12 22:09:48 volumio volumio[7509]: info: CoreStateMachine::pushState Jun 12 22:09:48 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:09:48 volumio volumio[7509]: info: CoreCommandRouter::volumioPushState Jun 12 22:09:48 volumio volumio[7509]: info: ControllerMpd::pushState Jun 12 22:09:48 volumio volumio[7509]: info: CoreCommandRouter::servicePushState Jun 12 22:09:48 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:09:48 volumio volumio[7509]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2477000,"duration":3425,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"832 Kbps","isStreaming":false,"title":"Naim - The Hi-Fi Collection.flac","artist":null,"album":null,"uri":"NAS/Nas/450Gb/Test/NAIM - The Hi-Fi Collection/Naim - The Hi-Fi Collection.flac","trackType":"flac"} Jun 12 22:09:48 volumio volumio[7509]: verbose: CURRENT POSITION 0 Jun 12 22:09:48 volumio volumio[7509]: info: CoreStateMachine::syncState stateService play Jun 12 22:09:48 volumio volumio[7509]: info: CoreStateMachine::syncState currentStatus play Jun 12 22:09:48 volumio volumio[7509]: info: Received an update from plugin. extracting info from payload Jun 12 22:09:48 volumio volumio[7509]: info: CoreStateMachine::pushState Jun 12 22:09:48 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:09:48 volumio volumio[7509]: info: CoreCommandRouter::volumioPushState Jun 12 22:09:48 volumio volumio[7509]: info: CoreStateMachine::pushState Jun 12 22:09:48 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:09:48 volumio volumio[7509]: info: CoreCommandRouter::volumioPushState Jun 12 22:09:48 volumio volumio[7509]: info: ------------------------------ 11ms Jun 12 22:09:48 volumio volumio[7509]: info: ------------------------------ 10ms Jun 12 22:09:53 volumio volumio[7509]: info: CoreCommandRouter::volumioGetQueue Jun 12 22:09:53 volumio volumio[7509]: info: CoreStateMachine::getQueue Jun 12 22:09:53 volumio volumio[7509]: info: CorePlayQueue::getQueue Jun 12 22:09:54 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 12 22:09:57 volumio volumio[7509]: info: CoreCommandRouter::volumioGetState Jun 12 22:09:57 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:09:59 volumio volumio[7509]: info: CoreCommandRouter::volumioGetState Jun 12 22:09:59 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:10:02 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 12 22:10:02 volumio volumio[7509]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 12 22:10:02 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 12 22:10:02 volumio volumio[7509]: info: Received Get System Version Jun 12 22:10:02 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 12 22:10:02 volumio volumio[7509]: info: Received Get System Info Jun 12 22:10:02 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 22:10:02 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 22:10:02 volumio volumio[7509]: info: Discovery: Getting this device information Jun 12 22:10:02 volumio volumio[7509]: info: CoreCommandRouter::volumioGetState Jun 12 22:10:02 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:10:02 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 22:10:07 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 12 22:10:07 volumio volumio[7509]: info: CURURI: music-library Jun 12 22:10:07 volumio volumio[7509]: info: Preload queue cleared Jun 12 22:10:09 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 12 22:10:09 volumio volumio[7509]: info: CURURI: music-library/NAS Jun 12 22:10:09 volumio volumio[7509]: info: Preload queue cleared Jun 12 22:10:09 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 12 22:10:09 volumio volumio[7509]: info: CURURI: music-library/NAS/Nas Jun 12 22:10:09 volumio volumio[7509]: info: Preload queue cleared Jun 12 22:10:10 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 12 22:10:10 volumio volumio[7509]: info: CURURI: music-library/NAS/Nas/Music Jun 12 22:10:10 volumio volumio[7509]: info: Preload queue cleared Jun 12 22:10:12 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 12 22:10:12 volumio volumio[7509]: info: CURURI: music-library/NAS/Nas/Music/Test Jun 12 22:10:12 volumio volumio[7509]: info: Preload queue cleared Jun 12 22:10:12 volumio volumio[7509]: info: Preloading song: music-library/NAS/Nas/Music/Test/ForUntoUsSurround88.flac Jun 12 22:10:12 volumio volumio[7509]: info: Preloading song: music-library/NAS/Nas/Music/Test/test192.flac Jun 12 22:10:12 volumio volumio[7509]: info: Preloading song: music-library/NAS/Nas/Music/Test/test192.m4a Jun 12 22:10:12 volumio volumio[7509]: info: Exploding uri music-library/NAS/Nas/Music/Test/ForUntoUsSurround88.flac in service mpd Jun 12 22:10:12 volumio volumio[7509]: info: ALBUMART /albumart?cacheid=478&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FMusic%2FTest%2FForUntoUsSurround88.flac&metadata=false Jun 12 22:10:12 volumio volumio[7509]: info: URI /mnt/NAS/Nas/Music/Test/ForUntoUsSurround88.flac Jun 12 22:10:12 volumio volumio[7509]: info: Exploding uri music-library/NAS/Nas/Music/Test/test192.flac in service mpd Jun 12 22:10:12 volumio volumio[7509]: info: ALBUMART /albumart?cacheid=478&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FMusic%2FTest%2Ftest192.flac&metadata=false Jun 12 22:10:12 volumio volumio[7509]: info: URI /mnt/NAS/Nas/Music/Test/test192.flac Jun 12 22:10:12 volumio volumio[7509]: info: Exploding uri music-library/NAS/Nas/Music/Test/test192.m4a in service mpd Jun 12 22:10:12 volumio volumio[7509]: info: ALBUMART /albumart?cacheid=478&web=N%5CA//extralarge&path=%2Fmnt%2FNAS%2FNas%2FMusic%2FTest%2Ftest192.m4a&metadata=false Jun 12 22:10:12 volumio volumio[7509]: info: URI /mnt/NAS/Nas/Music/Test/test192.m4a Jun 12 22:10:17 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 12 22:10:17 volumio volumio[7509]: info: CURURI: music-library/NAS/Nas/Music/Test/BitPerfectTest Jun 12 22:10:17 volumio volumio[7509]: info: Preload queue cleared Jun 12 22:10:17 volumio volumio[7509]: info: Preloading song: music-library/NAS/Nas/Music/Test/BitPerfectTest/192_16_adi2pro_bittest 4s.wav Jun 12 22:10:17 volumio volumio[7509]: info: Preloading song: music-library/NAS/Nas/Music/Test/BitPerfectTest/192_24_adi2pro_bittest 4s.wav Jun 12 22:10:17 volumio volumio[7509]: info: Preloading song: music-library/NAS/Nas/Music/Test/BitPerfectTest/192_32_adi2pro_bittest 4s.wav Jun 12 22:10:17 volumio volumio[7509]: info: Preloading song: music-library/NAS/Nas/Music/Test/BitPerfectTest/441_16_adi2pro_bittest 4s.wav Jun 12 22:10:17 volumio volumio[7509]: info: Preloading song: music-library/NAS/Nas/Music/Test/BitPerfectTest/441_24_adi2pro_bittest 4s.wav Jun 12 22:10:17 volumio volumio[7509]: info: Preloading song: music-library/NAS/Nas/Music/Test/BitPerfectTest/441_32_adi2pro_bittest 4s.wav Jun 12 22:10:17 volumio volumio[7509]: info: Preloading song: music-library/NAS/Nas/Music/Test/BitPerfectTest/96_16_adi2pro_bittest 4s.wav Jun 12 22:10:17 volumio volumio[7509]: info: Preloading song: music-library/NAS/Nas/Music/Test/BitPerfectTest/96_24_adi2pro_bittest 4s.wav Jun 12 22:10:17 volumio volumio[7509]: info: Preloading song: music-library/NAS/Nas/Music/Test/BitPerfectTest/96_32_adi2pro_bittest 4s.wav Jun 12 22:10:17 volumio volumio[7509]: info: Exploding uri music-library/NAS/Nas/Music/Test/BitPerfectTest/192_16_adi2pro_bittest 4s.wav in service mpd Jun 12 22:10:17 volumio volumio[7509]: info: ALBUMART /albumart?cacheid=478&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FMusic%2FTest%2FBitPerfectTest%2F192_16_adi2pro_bittest%204s.wav&metadata=false Jun 12 22:10:17 volumio volumio[7509]: info: URI /mnt/NAS/Nas/Music/Test/BitPerfectTest/192_16_adi2pro_bittest 4s.wav Jun 12 22:10:17 volumio volumio[7509]: info: Exploding uri music-library/NAS/Nas/Music/Test/BitPerfectTest/192_24_adi2pro_bittest 4s.wav in service mpd Jun 12 22:10:17 volumio volumio[7509]: info: ALBUMART /albumart?cacheid=478&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FMusic%2FTest%2FBitPerfectTest%2F192_24_adi2pro_bittest%204s.wav&metadata=false Jun 12 22:10:17 volumio volumio[7509]: info: URI /mnt/NAS/Nas/Music/Test/BitPerfectTest/192_24_adi2pro_bittest 4s.wav Jun 12 22:10:17 volumio volumio[7509]: info: Exploding uri music-library/NAS/Nas/Music/Test/BitPerfectTest/192_32_adi2pro_bittest 4s.wav in service mpd Jun 12 22:10:17 volumio volumio[7509]: info: ALBUMART /albumart?cacheid=478&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FMusic%2FTest%2FBitPerfectTest%2F192_32_adi2pro_bittest%204s.wav&metadata=false Jun 12 22:10:17 volumio volumio[7509]: info: URI /mnt/NAS/Nas/Music/Test/BitPerfectTest/192_32_adi2pro_bittest 4s.wav Jun 12 22:10:17 volumio volumio[7509]: info: Exploding uri music-library/NAS/Nas/Music/Test/BitPerfectTest/441_16_adi2pro_bittest 4s.wav in service mpd Jun 12 22:10:17 volumio volumio[7509]: info: ALBUMART /albumart?cacheid=478&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FMusic%2FTest%2FBitPerfectTest%2F441_16_adi2pro_bittest%204s.wav&metadata=false Jun 12 22:10:17 volumio volumio[7509]: info: URI /mnt/NAS/Nas/Music/Test/BitPerfectTest/441_16_adi2pro_bittest 4s.wav Jun 12 22:10:17 volumio volumio[7509]: info: Exploding uri music-library/NAS/Nas/Music/Test/BitPerfectTest/441_24_adi2pro_bittest 4s.wav in service mpd Jun 12 22:10:17 volumio volumio[7509]: info: ALBUMART /albumart?cacheid=478&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FMusic%2FTest%2FBitPerfectTest%2F441_24_adi2pro_bittest%204s.wav&metadata=false Jun 12 22:10:17 volumio volumio[7509]: info: URI /mnt/NAS/Nas/Music/Test/BitPerfectTest/441_24_adi2pro_bittest 4s.wav Jun 12 22:10:17 volumio volumio[7509]: info: Exploding uri music-library/NAS/Nas/Music/Test/BitPerfectTest/441_32_adi2pro_bittest 4s.wav in service mpd Jun 12 22:10:17 volumio volumio[7509]: info: ALBUMART /albumart?cacheid=478&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FMusic%2FTest%2FBitPerfectTest%2F441_32_adi2pro_bittest%204s.wav&metadata=false Jun 12 22:10:17 volumio volumio[7509]: info: URI /mnt/NAS/Nas/Music/Test/BitPerfectTest/441_32_adi2pro_bittest 4s.wav Jun 12 22:10:17 volumio volumio[7509]: info: Exploding uri music-library/NAS/Nas/Music/Test/BitPerfectTest/96_16_adi2pro_bittest 4s.wav in service mpd Jun 12 22:10:17 volumio volumio[7509]: info: ALBUMART /albumart?cacheid=478&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FMusic%2FTest%2FBitPerfectTest%2F96_16_adi2pro_bittest%204s.wav&metadata=false Jun 12 22:10:17 volumio volumio[7509]: info: URI /mnt/NAS/Nas/Music/Test/BitPerfectTest/96_16_adi2pro_bittest 4s.wav Jun 12 22:10:17 volumio volumio[7509]: info: Exploding uri music-library/NAS/Nas/Music/Test/BitPerfectTest/96_24_adi2pro_bittest 4s.wav in service mpd Jun 12 22:10:17 volumio volumio[7509]: info: ALBUMART /albumart?cacheid=478&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FMusic%2FTest%2FBitPerfectTest%2F96_24_adi2pro_bittest%204s.wav&metadata=false Jun 12 22:10:17 volumio volumio[7509]: info: URI /mnt/NAS/Nas/Music/Test/BitPerfectTest/96_24_adi2pro_bittest 4s.wav Jun 12 22:10:17 volumio volumio[7509]: info: Exploding uri music-library/NAS/Nas/Music/Test/BitPerfectTest/96_32_adi2pro_bittest 4s.wav in service mpd Jun 12 22:10:17 volumio volumio[7509]: info: ALBUMART /albumart?cacheid=478&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FMusic%2FTest%2FBitPerfectTest%2F96_32_adi2pro_bittest%204s.wav&metadata=false Jun 12 22:10:17 volumio volumio[7509]: info: URI /mnt/NAS/Nas/Music/Test/BitPerfectTest/96_32_adi2pro_bittest 4s.wav Jun 12 22:10:18 volumio volumio[7509]: info: Preload queue cleared Jun 12 22:10:18 volumio volumio[7509]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 12 22:10:18 volumio volumio[7509]: info: CoreStateMachine::ClearQueue Jun 12 22:10:18 volumio volumio[7509]: info: CoreStateMachine::stop Jun 12 22:10:18 volumio volumio[7509]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 22:10:18 volumio volumio[7509]: info: CoreStateMachine::stPlaybackTimer Jun 12 22:10:18 volumio volumio[7509]: info: CoreStateMachine::updateTrackBlock Jun 12 22:10:18 volumio volumio[7509]: info: CorePlayQueue::getTrackBlock Jun 12 22:10:18 volumio volumio[7509]: info: CoreStateMachine::pushState Jun 12 22:10:18 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:10:18 volumio volumio[7509]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 22:10:18 volumio volumio[7509]: info: CoreCommandRouter::volumioPushState Jun 12 22:10:18 volumio volumio[7509]: info: CoreStateMachine::serviceStop Jun 12 22:10:18 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:10:18 volumio volumio[7509]: info: CoreCommandRouter::serviceStop Jun 12 22:10:18 volumio volumio[7509]: info: ControllerMpd::stop Jun 12 22:10:18 volumio volumio[7509]: verbose: ControllerMpd::sendMpdCommand stop Jun 12 22:10:18 volumio volumio[7509]: info: CorePlayQueue::clearPlayQueue Jun 12 22:10:18 volumio volumio[7509]: info: CorePlayQueue::saveQueue Jun 12 22:10:18 volumio volumio[7509]: info: CoreCommandRouter::volumioPushQueue Jun 12 22:10:18 volumio volumio[7509]: info: CoreStateMachine::addQueueItems Jun 12 22:10:18 volumio volumio[7509]: info: CorePlayQueue::addQueueItems Jun 12 22:10:18 volumio volumio[7509]: info: Preload queue cleared Jun 12 22:10:18 volumio volumio[7509]: info: Adding Item to queue: music-library/NAS/Nas/Music/Test/BitPerfectTest/192_16_adi2pro_bittest 4s.wav Jun 12 22:10:18 volumio volumio[7509]: info: Using cached record of: music-library/NAS/Nas/Music/Test/BitPerfectTest/192_16_adi2pro_bittest 4s.wav Jun 12 22:10:18 volumio volumio[7509]: info: CoreCommandRouter::volumioPushQueue Jun 12 22:10:18 volumio volumio[7509]: info: CorePlayQueue::saveQueue Jun 12 22:10:18 volumio volumio[7509]: info: CoreStateMachine::updateTrackBlock Jun 12 22:10:18 volumio volumio[7509]: info: CorePlayQueue::getTrackBlock Jun 12 22:10:18 volumio volumio[7509]: info: CoreCommandRouter::volumioPlay Jun 12 22:10:18 volumio volumio[7509]: info: CoreStateMachine::play index 0 Jun 12 22:10:18 volumio volumio[7509]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 22:10:18 volumio volumio[7509]: info: CoreStateMachine::addQueueItems Jun 12 22:10:18 volumio volumio[7509]: info: CorePlayQueue::addQueueItems Jun 12 22:10:18 volumio volumio[7509]: info: Preload queue cleared Jun 12 22:10:18 volumio volumio[7509]: info: Adding Item to queue: music-library/NAS/Nas/Music/Test/BitPerfectTest/192_24_adi2pro_bittest 4s.wav Jun 12 22:10:18 volumio volumio[7509]: info: Using cached record of: music-library/NAS/Nas/Music/Test/BitPerfectTest/192_24_adi2pro_bittest 4s.wav Jun 12 22:10:18 volumio volumio[7509]: info: Adding Item to queue: music-library/NAS/Nas/Music/Test/BitPerfectTest/192_32_adi2pro_bittest 4s.wav Jun 12 22:10:18 volumio volumio[7509]: info: Using cached record of: music-library/NAS/Nas/Music/Test/BitPerfectTest/192_32_adi2pro_bittest 4s.wav Jun 12 22:10:18 volumio volumio[7509]: info: Adding Item to queue: music-library/NAS/Nas/Music/Test/BitPerfectTest/441_16_adi2pro_bittest 4s.wav Jun 12 22:10:18 volumio volumio[7509]: info: Using cached record of: music-library/NAS/Nas/Music/Test/BitPerfectTest/441_16_adi2pro_bittest 4s.wav Jun 12 22:10:18 volumio volumio[7509]: info: Adding Item to queue: music-library/NAS/Nas/Music/Test/BitPerfectTest/441_24_adi2pro_bittest 4s.wav Jun 12 22:10:18 volumio volumio[7509]: info: Using cached record of: music-library/NAS/Nas/Music/Test/BitPerfectTest/441_24_adi2pro_bittest 4s.wav Jun 12 22:10:18 volumio volumio[7509]: info: Adding Item to queue: music-library/NAS/Nas/Music/Test/BitPerfectTest/441_32_adi2pro_bittest 4s.wav Jun 12 22:10:18 volumio volumio[7509]: info: Using cached record of: music-library/NAS/Nas/Music/Test/BitPerfectTest/441_32_adi2pro_bittest 4s.wav Jun 12 22:10:18 volumio volumio[7509]: info: Adding Item to queue: music-library/NAS/Nas/Music/Test/BitPerfectTest/96_16_adi2pro_bittest 4s.wav Jun 12 22:10:18 volumio volumio[7509]: info: Using cached record of: music-library/NAS/Nas/Music/Test/BitPerfectTest/96_16_adi2pro_bittest 4s.wav Jun 12 22:10:18 volumio volumio[7509]: info: Adding Item to queue: music-library/NAS/Nas/Music/Test/BitPerfectTest/96_24_adi2pro_bittest 4s.wav Jun 12 22:10:18 volumio volumio[7509]: info: Using cached record of: music-library/NAS/Nas/Music/Test/BitPerfectTest/96_24_adi2pro_bittest 4s.wav Jun 12 22:10:18 volumio volumio[7509]: info: Adding Item to queue: music-library/NAS/Nas/Music/Test/BitPerfectTest/96_32_adi2pro_bittest 4s.wav Jun 12 22:10:18 volumio volumio[7509]: info: Using cached record of: music-library/NAS/Nas/Music/Test/BitPerfectTest/96_32_adi2pro_bittest 4s.wav Jun 12 22:10:18 volumio volumio[7509]: info: Adding Item to queue: music-library/NAS/Nas/Music/Test/BitPerfectTest/bit_test_wavs.zip Jun 12 22:10:18 volumio volumio[7509]: info: Exploding uri music-library/NAS/Nas/Music/Test/BitPerfectTest/bit_test_wavs.zip in service mpd Jun 12 22:10:18 volumio volumio[7509]: info: CoreStateMachine::stop Jun 12 22:10:18 volumio volumio[7509]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 22:10:18 volumio volumio[7509]: info: CoreStateMachine::play index undefined Jun 12 22:10:18 volumio volumio[7509]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 22:10:18 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:10:18 volumio volumio[7509]: info: CoreStateMachine::startPlaybackTimer Jun 12 22:10:18 volumio volumio[7509]: info: CorePlayQueue::getTrack 0 Jun 12 22:10:18 volumio volumio[7509]: verbose: ControllerMpd::clearAddPlayTracks NAS/Nas/Music/Test/BitPerfectTest/192_16_adi2pro_bittest 4s.wav Jun 12 22:10:18 volumio volumio[7509]: verbose: ControllerMpd::sendMpdCommand stop Jun 12 22:10:18 volumio volumio[7509]: info: Jun 12 22:10:18 volumio volumio[7509]: ---------------------------- MPD announces state update: player Jun 12 22:10:18 volumio volumio[7509]: info: ALBUMART /albumart?cacheid=478&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FMusic%2FTest%2FBitPerfectTest%2Fbit_test_wavs.zip&metadata=false Jun 12 22:10:18 volumio volumio[7509]: info: URI /mnt/NAS/Nas/Music/Test/BitPerfectTest/bit_test_wavs.zip Jun 12 22:10:18 volumio volumio[7509]: info: ALBUMART /albumart?cacheid=478&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FMusic%2FTest%2FBitPerfectTest%2Fbit_test_wavs.zip&metadata=false Jun 12 22:10:18 volumio volumio[7509]: info: URI /mnt/NAS/Nas/Music/Test/BitPerfectTest/bit_test_wavs.zip Jun 12 22:10:18 volumio volumio[7509]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 22:10:18 volumio volumio[7509]: Error: Unable to resolve or reject the same promise twice Jun 12 22:10:18 volumio volumio[7509]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jun 12 22:10:18 volumio volumio[7509]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Jun 12 22:10:18 volumio volumio[7509]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jun 12 22:10:18 volumio volumio[7509]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jun 12 22:10:18 volumio volumio[7509]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jun 12 22:10:18 volumio volumio[7509]: at Socket.emit (events.js:315:20) Jun 12 22:10:18 volumio volumio[7509]: at addChunk (internal/streams/readable.js:309:12) Jun 12 22:10:18 volumio volumio[7509]: at readableAddChunk (internal/streams/readable.js:280:11) Jun 12 22:10:18 volumio volumio[7509]: at Socket.Readable.push (internal/streams/readable.js:223:10) Jun 12 22:10:18 volumio volumio[7509]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Jun 12 22:10:18 volumio volumio[7509]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 22:10:18 volumio sudo[26716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-12 22:09 Jun 12 22:10:18 volumio sudo[26716]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"