Feb 25 17:54:11 volumio kernel: usb 1-1.5: new high-speed USB device number 14 using dwc_otg Feb 25 17:54:11 volumio kernel: usb 1-1.5: config 1 has an invalid interface number: 9 but max is 2 Feb 25 17:54:11 volumio kernel: usb 1-1.5: config 1 has no interface number 2 Feb 25 17:54:11 volumio kernel: usb 1-1.5: New USB device found, idVendor=1019, idProduct=0010, bcdDevice= 0.10 Feb 25 17:54:11 volumio kernel: usb 1-1.5: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 25 17:54:11 volumio kernel: usb 1-1.5: Product: FOSTEX USB AUDIO HP-A8 Feb 25 17:54:11 volumio kernel: usb 1-1.5: Manufacturer: FOSTEX Feb 25 17:54:11 volumio kernel: usb 1-1.5: SerialNumber: 00000 Feb 25 17:54:11 volumio kernel: input: FOSTEX FOSTEX USB AUDIO HP-A8 as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.9/0003:1019:0010.000B/input/input12 Feb 25 17:54:11 volumio kernel: hid-generic 0003:1019:0010.000B: input,hidraw0: USB HID v1.00 Device [FOSTEX FOSTEX USB AUDIO HP-A8] on usb-3f980000.usb-1.5/input9 Feb 25 17:54:11 volumio (udev-worker)[21455]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Feb 25 17:54:11 volumio volumio[1206]: info: Feb 25 17:54:11 volumio volumio[1206]: ---------------------------- USB Audio Device Attached Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::Close All Modals sent Feb 25 17:54:11 volumio volumio[1206]: info: Preparing to save Alsa Options, stopping services first Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::volumioGetState Feb 25 17:54:11 volumio volumio[1206]: info: CorePlayQueue::getTrack 2 Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::volumioPause Feb 25 17:54:11 volumio volumio[1206]: info: CoreStateMachine::pause Feb 25 17:54:11 volumio volumio[1206]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"FOSTEX USB AUDIO HP-A8","alsacard":"HPA8"},"i2s":false} Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 17:54:11 volumio systemd-logind[683]: Watching system buttons on /dev/input/event2 (FOSTEX FOSTEX USB AUDIO HP-A8) Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 17:54:11 volumio volumio[1206]: info: Device 5 does not have any Mixer Control Available Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 25 17:54:11 volumio volumio[1206]: info: Updating Volume Controller Parameters: Device: 5 Name: FOSTEX USB AUDIO HP-A8 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 25 17:54:11 volumio volumio[1206]: info: Disabling external Volume Control Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 17:54:11 volumio volumio[1206]: info: CoreStateMachine::pushState Feb 25 17:54:11 volumio volumio[1206]: info: CorePlayQueue::getTrack 2 Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::volumioPushState Feb 25 17:54:11 volumio volumio[1206]: info: Preparing to generate the ALSA configuration file Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 25 17:54:11 volumio volumio[1206]: info: Asound.conf file unchanged, so no further update is needed Feb 25 17:54:11 volumio volumio[1206]: info: Output device has changed, restarting MPD Feb 25 17:54:11 volumio volumio[1206]: info: Output device has changed, restarting Shairport Sync Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 17:54:11 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 17:54:11 volumio sudo[21487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 17:54:11 volumio sudo[21487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 17:54:12 volumio sudo[21487]: pam_unix(sudo:session): session closed for user root Feb 25 17:54:12 volumio sudo[21489]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 17:54:12 volumio sudo[21489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 17:54:12 volumio volumio[1206]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 17:54:12 volumio volumio[1206]: info: MPD Permissions set Feb 25 17:54:12 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 17:54:12 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 17:54:12 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 17:54:12 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 17:54:12 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 17:54:12 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 17:54:12 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 17:54:12 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 17:54:12 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 17:54:12 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 17:54:12 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 25 17:54:12 volumio volumio[1206]: info: Starting Shairport Sync Feb 25 17:54:12 volumio sudo[21502]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 17:54:12 volumio sudo[21502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 17:54:12 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 25 17:54:12 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 25 17:54:12 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 17:54:12 volumio systemd[1]: shairport-sync.service: Consumed 4min 38.141s CPU time. Feb 25 17:54:12 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 17:54:12 volumio sudo[21502]: pam_unix(sudo:session): session closed for user root Feb 25 17:54:12 volumio volumio[1206]: info: Shairport-Sync Started Feb 25 17:54:12 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 25 17:54:12 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 25 17:54:12 volumio systemd[1]: mpd.service: Consumed 1min 700ms CPU time. Feb 25 17:54:12 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 25 17:54:12 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 25 17:54:12 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 25 17:54:12 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 25 17:54:12 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 25 17:54:12 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Feb 25 17:54:12 volumio sudo[21517]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 17:54:12 volumio sudo[21517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 25 17:54:12 volumio sudo[21517]: pam_unix(sudo:session): session closed for user root Feb 25 17:54:15 volumio mpd[21522]: 2026-02-25T17:54:15 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 17:54:15 volumio volumio[1206]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 25 17:54:15 volumio volumio[1206]: info: CoreStateMachine::getcurrentVolume Feb 25 17:54:15 volumio volumio[1206]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 17:54:15 volumio volumio[1206]: info: CoreStateMachine::pushState Feb 25 17:54:15 volumio volumio[1206]: info: CorePlayQueue::getTrack 2 Feb 25 17:54:15 volumio volumio[1206]: info: CoreCommandRouter::volumioPushState Feb 25 17:54:16 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 25 17:54:16 volumio sudo[21489]: pam_unix(sudo:session): session closed for user root Feb 25 17:54:16 volumio volumio[1206]: error: updateQueue error: null Feb 25 17:55:37 volumio volumio[1206]: verbose: New Socket.io Connection to 192.168.0.108 from 192.168.0.104 UA: Mozilla/5.0 (Linux; arm_64; Android 15; 2311DRK48G) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.7444.51 YaBrowser/25.12.7.51.00 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::volumioGetVisibleSources Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::volumioGetState Feb 25 17:55:37 volumio volumio[1206]: info: CorePlayQueue::getTrack 2 Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::volumioGetQueue Feb 25 17:55:37 volumio volumio[1206]: info: CoreStateMachine::getQueue Feb 25 17:55:37 volumio volumio[1206]: info: CorePlayQueue::getQueue Feb 25 17:55:37 volumio volumio[1206]: info: Listing playlists Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 25 17:55:37 volumio volumio[1206]: info: Received Get System Info Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 17:55:37 volumio volumio[1206]: info: Discovery: Getting this device information Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::volumioGetState Feb 25 17:55:37 volumio volumio[1206]: info: CorePlayQueue::getTrack 2 Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::volumioGetState Feb 25 17:55:37 volumio volumio[1206]: info: CorePlayQueue::getTrack 2 Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 17:55:37 volumio volumio[1206]: info: Received Get System Info Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 17:55:37 volumio volumio[1206]: info: Discovery: Getting this device information Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::volumioGetState Feb 25 17:55:37 volumio volumio[1206]: info: CorePlayQueue::getTrack 2 Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 17:55:37 volumio volumio[1206]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 25 17:55:37 volumio volumio[1206]: info: Received Get System Version Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 17:55:37 volumio volumio[1206]: info: Received Get System Info Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 17:55:37 volumio volumio[1206]: info: Discovery: Getting this device information Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::volumioGetState Feb 25 17:55:37 volumio volumio[1206]: info: CorePlayQueue::getTrack 2 Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::volumioGetVisibleSources Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::volumioGetState Feb 25 17:55:37 volumio volumio[1206]: info: CorePlayQueue::getTrack 2 Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::volumioGetQueue Feb 25 17:55:37 volumio volumio[1206]: info: CoreStateMachine::getQueue Feb 25 17:55:37 volumio volumio[1206]: info: CorePlayQueue::getQueue Feb 25 17:55:37 volumio volumio[1206]: info: Listing playlists Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 25 17:55:37 volumio volumio[1206]: info: Received Get System Info Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 17:55:37 volumio volumio[1206]: info: Discovery: Getting this device information Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::volumioGetState Feb 25 17:55:37 volumio volumio[1206]: info: CorePlayQueue::getTrack 2 Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::volumioGetState Feb 25 17:55:37 volumio volumio[1206]: info: CorePlayQueue::getTrack 2 Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 25 17:55:37 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 25 17:55:38 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 17:55:38 volumio volumio[1206]: info: Received Get System Info Feb 25 17:55:38 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 17:55:38 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 17:55:38 volumio volumio[1206]: info: Discovery: Getting this device information Feb 25 17:55:38 volumio volumio[1206]: info: CoreCommandRouter::volumioGetState Feb 25 17:55:38 volumio volumio[1206]: info: CorePlayQueue::getTrack 2 Feb 25 17:55:38 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 17:55:39 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 25 17:55:43 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 25 17:55:43 volumio volumio[1206]: info: CURURI: music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q Feb 25 17:55:43 volumio volumio[1206]: info: Preload queue cleared Feb 25 17:55:44 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 25 17:55:44 volumio volumio[1206]: info: CURURI: music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE Feb 25 17:55:44 volumio volumio[1206]: info: Preload queue cleared Feb 25 17:55:44 volumio volumio[1206]: info: Preloading song: music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/Ike Quebec [Blue & Sentimental][1962](2016)[24-192][TR24][OF].aiff Feb 25 17:55:44 volumio volumio[1206]: info: Exploding uri music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/Ike Quebec [Blue & Sentimental][1962](2016)[24-192][TR24][OF].aiff in service mpd Feb 25 17:55:44 volumio volumio[1206]: info: ALBUMART /albumart?cacheid=308&web=Ike%20Quebec/Blue%20%26%20Sentimental/extralarge&path=%2Fmnt%2FNAS%2FTosh%2F%D0%90%D1%83%D0%B4%D0%B8%D0%BE%2F_Alphabet_Jzz%2FQ%2FQUEBEC%20IKE%2FIke%20Quebec%20%5BBlue%20%26%20Sentimental%5D%5B1962%5D(2016)%5B24-192%5D%5BTR24%5D%5BOF%5D.aiff&metadata=false Feb 25 17:55:44 volumio volumio[1206]: info: URI /mnt/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/Ike Quebec [Blue & Sentimental][1962](2016)[24-192][TR24][OF].aiff Feb 25 17:55:45 volumio volumio[1206]: info: Preload queue cleared Feb 25 17:55:45 volumio volumio[1206]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 25 17:55:45 volumio volumio[1206]: info: CoreStateMachine::ClearQueue Feb 25 17:55:45 volumio volumio[1206]: info: CoreStateMachine::stop Feb 25 17:55:45 volumio volumio[1206]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:55:45 volumio volumio[1206]: info: CoreStateMachine::updateTrackBlock Feb 25 17:55:45 volumio volumio[1206]: info: CorePlayQueue::getTrackBlock Feb 25 17:55:45 volumio volumio[1206]: info: CoreStateMachine::stPlaybackTimer Feb 25 17:55:45 volumio volumio[1206]: info: CoreStateMachine::pushState Feb 25 17:55:45 volumio volumio[1206]: info: CorePlayQueue::getTrack 2 Feb 25 17:55:45 volumio volumio[1206]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 17:55:45 volumio volumio[1206]: info: CoreCommandRouter::volumioPushState Feb 25 17:55:45 volumio volumio[1206]: info: CoreStateMachine::serviceStop Feb 25 17:55:45 volumio volumio[1206]: info: CorePlayQueue::getTrack 2 Feb 25 17:55:45 volumio volumio[1206]: info: CoreCommandRouter::serviceStop Feb 25 17:55:45 volumio volumio[1206]: info: ControllerMpd::stop Feb 25 17:55:45 volumio volumio[1206]: verbose: ControllerMpd::sendMpdCommand stop Feb 25 17:55:45 volumio volumio[1206]: info: CorePlayQueue::clearPlayQueue Feb 25 17:55:45 volumio volumio[1206]: info: CorePlayQueue::saveQueue Feb 25 17:55:45 volumio volumio[1206]: info: CoreCommandRouter::volumioPushQueue Feb 25 17:55:45 volumio volumio[1206]: info: CoreStateMachine::addQueueItems Feb 25 17:55:45 volumio volumio[1206]: info: CorePlayQueue::addQueueItems Feb 25 17:55:45 volumio volumio[1206]: info: Preload queue cleared Feb 25 17:55:45 volumio volumio[1206]: info: Adding Item to queue: music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/Ike Quebec [Blue & Sentimental][1962](2016)[24-192][TR24][OF].aiff Feb 25 17:55:45 volumio volumio[1206]: info: Using cached record of: music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/Ike Quebec [Blue & Sentimental][1962](2016)[24-192][TR24][OF].aiff Feb 25 17:55:45 volumio volumio[1206]: info: CoreCommandRouter::volumioPushQueue Feb 25 17:55:45 volumio volumio[1206]: info: CorePlayQueue::saveQueue Feb 25 17:55:45 volumio volumio[1206]: info: CoreStateMachine::updateTrackBlock Feb 25 17:55:45 volumio volumio[1206]: info: CorePlayQueue::getTrackBlock Feb 25 17:55:45 volumio volumio[1206]: info: CoreCommandRouter::volumioPlay Feb 25 17:55:45 volumio volumio[1206]: info: CoreStateMachine::play index 0 Feb 25 17:55:45 volumio volumio[1206]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:55:45 volumio volumio[1206]: info: CoreStateMachine::addQueueItems Feb 25 17:55:45 volumio volumio[1206]: info: CorePlayQueue::addQueueItems Feb 25 17:55:45 volumio volumio[1206]: info: Preload queue cleared Feb 25 17:55:45 volumio volumio[1206]: info: Adding Item to queue: music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/1950-60 - Easy Living Feb 25 17:55:45 volumio volumio[1206]: info: Exploding uri music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/1950-60 - Easy Living in service mpd Feb 25 17:55:45 volumio volumio[1206]: info: Adding Item to queue: music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/1961 - Heavy Soul (JP.Re.DSD128) Feb 25 17:55:45 volumio volumio[1206]: info: Exploding uri music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/1961 - Heavy Soul (JP.Re.DSD128) in service mpd Feb 25 17:55:45 volumio volumio[1206]: info: Adding Item to queue: music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/1962 - Blue & Sentimental (Analogue Productions 45 rpm) Feb 25 17:55:45 volumio volumio[1206]: info: Exploding uri music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/1962 - Blue & Sentimental (Analogue Productions 45 rpm) in service mpd Feb 25 17:55:45 volumio volumio[1206]: info: Adding Item to queue: music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/1987 - Easy Living Feb 25 17:55:45 volumio volumio[1206]: info: Exploding uri music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/1987 - Easy Living in service mpd Feb 25 17:55:45 volumio volumio[1206]: info: Adding Item to queue: music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/1992 - Art Of Ike Quebec Feb 25 17:55:45 volumio volumio[1206]: info: Exploding uri music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/1992 - Art Of Ike Quebec in service mpd Feb 25 17:55:45 volumio volumio[1206]: info: Adding Item to queue: music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/1997 - Ballads Feb 25 17:55:45 volumio volumio[1206]: info: Exploding uri music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/1997 - Ballads in service mpd Feb 25 17:55:45 volumio volumio[1206]: info: Adding Item to queue: music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/2004 - Blue Harlem Feb 25 17:55:45 volumio volumio[1206]: info: Exploding uri music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/2004 - Blue Harlem in service mpd Feb 25 17:55:45 volumio volumio[1206]: info: Adding Item to queue: music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/2005 - The Complete Blue Note 45 Sessions [2CD] Feb 25 17:55:45 volumio volumio[1206]: info: Exploding uri music-library/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/2005 - The Complete Blue Note 45 Sessions [2CD] in service mpd Feb 25 17:55:45 volumio volumio[1206]: info: CoreStateMachine::stop Feb 25 17:55:45 volumio volumio[1206]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:55:45 volumio volumio[1206]: info: CoreStateMachine::play index undefined Feb 25 17:55:45 volumio volumio[1206]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:55:45 volumio volumio[1206]: info: CorePlayQueue::getTrack 0 Feb 25 17:55:45 volumio volumio[1206]: info: CoreStateMachine::startPlaybackTimer Feb 25 17:55:45 volumio volumio[1206]: info: CorePlayQueue::getTrack 0 Feb 25 17:55:45 volumio volumio[1206]: verbose: ControllerMpd::clearAddPlayTracks NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/Ike Quebec [Blue & Sentimental][1962](2016)[24-192][TR24][OF].aiff Feb 25 17:55:45 volumio volumio[1206]: verbose: ControllerMpd::sendMpdCommand stop Feb 25 17:55:45 volumio volumio[1206]: info: ALBUMART /albumart?cacheid=308&web=//extralarge&path=%2Fmnt%2FNAS%2FTosh%2F%D0%90%D1%83%D0%B4%D0%B8%D0%BE%2F_Alphabet_Jzz%2FQ%2FQUEBEC%20IKE%2F1950-60%20-%20Easy%20Living%2FIke%20Quebec%20-%20Easy%20Living.ape&metadata=false Feb 25 17:55:45 volumio volumio[1206]: info: URI /mnt/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/1950-60 - Easy Living/Ike Quebec - Easy Living.ape Feb 25 17:55:45 volumio volumio[1206]: info: ALBUMART /albumart?cacheid=308&web=Ike%20Quebec/Easy%20Living/extralarge&path=%2Fmnt%2FNAS%2FTosh%2F%D0%90%D1%83%D0%B4%D0%B8%D0%BE%2F_Alphabet_Jzz%2FQ%2FQUEBEC%20IKE%2F1950-60%20-%20Easy%20Living%2FIke%20Quebec%20-%20Easy%20Living.cue&metadata=false Feb 25 17:55:45 volumio volumio[1206]: info: URI /mnt/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/1950-60 - Easy Living/Ike Quebec - Easy Living.cue Feb 25 17:55:45 volumio volumio[1206]: info: ALBUMART /albumart?cacheid=308&web=Ike%20Quebec/Easy%20Living/extralarge&path=%2Fmnt%2FNAS%2FTosh%2F%D0%90%D1%83%D0%B4%D0%B8%D0%BE%2F_Alphabet_Jzz%2FQ%2FQUEBEC%20IKE%2F1950-60%20-%20Easy%20Living%2FIke%20Quebec%20-%20Easy%20Living.cue&metadata=false Feb 25 17:55:45 volumio volumio[1206]: info: URI /mnt/NAS/Tosh/Аудио/_Alphabet_Jzz/Q/QUEBEC IKE/1950-60 - Easy Living/Ike Quebec - Easy Living.cue Feb 25 17:55:45 volumio volumio[1206]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 17:55:45 volumio volumio[1206]: Error: Unable to resolve or reject the same promise twice Feb 25 17:55:45 volumio volumio[1206]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Feb 25 17:55:45 volumio volumio[1206]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Feb 25 17:55:45 volumio volumio[1206]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Feb 25 17:55:45 volumio volumio[1206]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Feb 25 17:55:45 volumio volumio[1206]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Feb 25 17:55:45 volumio volumio[1206]: at Socket.emit (node:events:514:28) Feb 25 17:55:45 volumio volumio[1206]: at addChunk (node:internal/streams/readable:343:12) Feb 25 17:55:45 volumio volumio[1206]: at readableAddChunk (node:internal/streams/readable:312:11) Feb 25 17:55:45 volumio volumio[1206]: at Readable.push (node:internal/streams/readable:253:10) Feb 25 17:55:45 volumio volumio[1206]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Feb 25 17:55:45 volumio volumio[1206]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 17:55:46 volumio sudo[21687]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 17:54' Feb 25 17:55:46 volumio sudo[21687]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"