Jul 08 10:46:29 volumio3 volumio[1032]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::volumioGetState Jul 08 10:46:29 volumio3 volumio[1032]: info: CorePlayQueue::getTrack 1 Jul 08 10:46:29 volumio3 volumio[1032]: info: Restoring Previous Volume level: 100 false false Jul 08 10:46:29 volumio3 volumio[1032]: info: Disable softmixer device for audio device Jul 08 10:46:29 volumio3 volumio[1032]: info: Output device has changed, restarting MPD Jul 08 10:46:29 volumio3 volumio[1032]: info: Output device has changed, restarting Shairport Sync Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 10:46:29 volumio3 sudo[4409]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 08 10:46:29 volumio3 sudo[4409]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 08 10:46:29 volumio3 sudo[4411]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 08 10:46:29 volumio3 sudo[4411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 08 10:46:29 volumio3 sudo[4409]: pam_unix(sudo:session): session closed for user root Jul 08 10:46:29 volumio3 volumio[1032]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 08 10:46:29 volumio3 volumio[1032]: info: Volume configurations have been set Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 08 10:46:29 volumio3 volumio[1032]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 08 10:46:29 volumio3 volumio[1032]: info: Disabling external Volume Control Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreStateMachine::pushState Jul 08 10:46:29 volumio3 volumio[1032]: info: CorePlayQueue::getTrack 1 Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::volumioPushState Jul 08 10:46:29 volumio3 volumio[1032]: info: Soft Volume ALSA configuration file deleted Jul 08 10:46:29 volumio3 volumio[1032]: info: Preparing to generate the ALSA configuration file Jul 08 10:46:29 volumio3 volumio[1032]: info: MPD Permissions set Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:46:29 volumio3 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 08 10:46:29 volumio3 systemd[1]: Stopping mpd.service - Music Player Daemon... Jul 08 10:46:29 volumio3 dbus-daemon[517]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.43' (uid=0 pid=4428 comm="timedatectl show --property=NTPSynchronized --valu") Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 08 10:46:29 volumio3 volumio[1032]: info: Getting Alsa Cards List without I2S DAC Jul 08 10:46:29 volumio3 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 08 10:46:29 volumio3 systemd[1]: mpd.service: Deactivated successfully. Jul 08 10:46:29 volumio3 systemd[1]: Stopped mpd.service - Music Player Daemon. Jul 08 10:46:29 volumio3 systemd[1]: mpd.service: Consumed 2.866s CPU time. Jul 08 10:46:29 volumio3 systemd[1]: mpd.socket: Deactivated successfully. Jul 08 10:46:29 volumio3 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jul 08 10:46:29 volumio3 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 08 10:46:29 volumio3 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 10:46:29 volumio3 systemd[1]: Starting mpd.service - Music Player Daemon... Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 08 10:46:29 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 08 10:46:29 volumio3 volumio[1032]: info: Starting Shairport Sync Jul 08 10:46:30 volumio3 volumio[1032]: info: Asound.conf file written Jul 08 10:46:30 volumio3 sudo[4447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 08 10:46:30 volumio3 sudo[4447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 08 10:46:30 volumio3 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jul 08 10:46:30 volumio3 sudo[4450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 08 10:46:30 volumio3 systemd[1]: shairport-sync.service: Deactivated successfully. Jul 08 10:46:30 volumio3 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 08 10:46:30 volumio3 sudo[4450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 08 10:46:30 volumio3 systemd[1]: shairport-sync.service: Consumed 4.422s CPU time. Jul 08 10:46:30 volumio3 sudo[4450]: pam_unix(sudo:session): session closed for user root Jul 08 10:46:30 volumio3 sudo[4439]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 08 10:46:30 volumio3 sudo[4439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 08 10:46:30 volumio3 sudo[4439]: pam_unix(sudo:session): session closed for user root Jul 08 10:46:30 volumio3 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 08 10:46:30 volumio3 sudo[4447]: pam_unix(sudo:session): session closed for user root Jul 08 10:46:30 volumio3 volumio[1032]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jul 08 10:46:30 volumio3 volumio[1032]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jul 08 10:46:30 volumio3 volumio[1032]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Jul 08 10:46:30 volumio3 volumio[1032]: No state is present for card sndrpihifiberry Jul 08 10:46:30 volumio3 volumio[1032]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Jul 08 10:46:30 volumio3 volumio[1032]: Found hardware: "RPi-simple" "" "" "" "" Jul 08 10:46:30 volumio3 volumio[1032]: Hardware is initialized using a generic method Jul 08 10:46:30 volumio3 volumio[1032]: No state is present for card sndrpihifiberry Jul 08 10:46:30 volumio3 volumio[1032]: info: Output device has changed, restarting MPD Jul 08 10:46:30 volumio3 volumio[1032]: info: Output device has changed, restarting Shairport Sync Jul 08 10:46:30 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:46:30 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 10:46:30 volumio3 dbus-daemon[517]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 08 10:46:30 volumio3 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 08 10:46:30 volumio3 setdatetime-helper.sh[4421]: Time is not synchronized. Attempting to sync... Jul 08 10:46:30 volumio3 volumio[1032]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 08 10:46:30 volumio3 sudo[4460]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 08 10:46:30 volumio3 sudo[4460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 08 10:46:30 volumio3 sudo[4462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 08 10:46:30 volumio3 sudo[4462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 08 10:46:30 volumio3 volumio[1032]: info: Shairport-Sync Started Jul 08 10:46:30 volumio3 sudo[4460]: pam_unix(sudo:session): session closed for user root Jul 08 10:46:30 volumio3 volumio[1032]: info: MPD Permissions set Jul 08 10:46:30 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:46:30 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:46:30 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:46:30 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:46:30 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:46:30 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:46:30 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:46:30 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:46:30 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 10:46:30 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 10:46:30 volumio3 volumio[1032]: info: Starting Shairport Sync Jul 08 10:46:30 volumio3 systemd[1]: mpd.service: Deactivated successfully. Jul 08 10:46:30 volumio3 systemd[1]: Stopped mpd.service - Music Player Daemon. Jul 08 10:46:30 volumio3 systemd[1]: mpd.socket: Deactivated successfully. Jul 08 10:46:30 volumio3 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jul 08 10:46:30 volumio3 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jul 08 10:46:30 volumio3 sudo[4494]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 08 10:46:30 volumio3 sudo[4494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 08 10:46:30 volumio3 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jul 08 10:46:30 volumio3 systemd[1]: Starting mpd.service - Music Player Daemon... Jul 08 10:46:30 volumio3 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jul 08 10:46:30 volumio3 systemd[1]: shairport-sync.service: Deactivated successfully. Jul 08 10:46:30 volumio3 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 08 10:46:30 volumio3 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 08 10:46:30 volumio3 sudo[4494]: pam_unix(sudo:session): session closed for user root Jul 08 10:46:30 volumio3 sudo[4499]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Tue, 08 Jul 2025 07:46:30 GMT#015' Jul 08 10:46:30 volumio3 volumio[1032]: info: Shairport-Sync Started Jul 08 10:46:30 volumio3 sudo[4499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 08 10:46:30 volumio3 setdatetime-helper.sh[4501]: Tue Jul 8 10:46:30 MSK 2025 Jul 08 10:46:30 volumio3 setdatetime-helper.sh[4421]: Time synchronized successfully. Jul 08 10:46:30 volumio3 sudo[4499]: pam_unix(sudo:session): session closed for user root Jul 08 10:46:30 volumio3 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 08 10:46:30 volumio3 systemd-journald[316]: Time jumped backwards, rotating. Jul 08 10:46:30 volumio3 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 08 10:46:30 volumio3 sudo[4498]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 08 10:46:30 volumio3 sudo[4498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 08 10:46:30 volumio3 sudo[4498]: pam_unix(sudo:session): session closed for user root Jul 08 10:46:32 volumio3 mpd[4518]: 2025-07-08T10:46:32 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 08 10:46:32 volumio3 systemd[1]: Started mpd.service - Music Player Daemon. Jul 08 10:46:32 volumio3 sudo[4411]: pam_unix(sudo:session): session closed for user root Jul 08 10:46:32 volumio3 sudo[4462]: pam_unix(sudo:session): session closed for user root Jul 08 10:46:32 volumio3 volumio[1032]: error: MPD error: The expression evaluated to a falsy value: Jul 08 10:46:32 volumio3 volumio[1032]: assert.ok(self.idling) Jul 08 10:46:32 volumio3 volumio[1032]: error: The expression evaluated to a falsy value: Jul 08 10:46:32 volumio3 volumio[1032]: assert.ok(self.idling) Jul 08 10:46:32 volumio3 volumio[1032]: error: updateQueue error: null Jul 08 10:46:33 volumio3 volumio[1032]: info: VolumeController::SetAlsaVolume100 Jul 08 10:46:33 volumio3 volumio[1032]: info: CoreStateMachine::pushState Jul 08 10:46:33 volumio3 volumio[1032]: info: CorePlayQueue::getTrack 1 Jul 08 10:46:33 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 10:46:33 volumio3 volumio[1032]: info: CoreCommandRouter::volumioPushState Jul 08 10:46:59 volumio3 systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... Jul 08 10:46:59 volumio3 systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 08 10:46:59 volumio3 systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Jul 08 10:46:59 volumio3 systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. Jul 08 10:46:59 volumio3 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully. Jul 08 10:47:09 volumio3 volumio[1032]: info: CoreCommandRouter::volumioGetState Jul 08 10:47:09 volumio3 volumio[1032]: info: CorePlayQueue::getTrack 1 Jul 08 10:47:15 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 08 10:47:15 volumio3 volumio[1032]: info: CURURI: music-library Jul 08 10:47:15 volumio3 volumio[1032]: info: Preload queue cleared Jul 08 10:47:17 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 08 10:47:17 volumio3 volumio[1032]: info: CURURI: music-library/USB Jul 08 10:47:17 volumio3 volumio[1032]: info: Preload queue cleared Jul 08 10:47:19 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 08 10:47:19 volumio3 volumio[1032]: info: CURURI: music-library/USB/Apacer Jul 08 10:47:20 volumio3 volumio[1032]: info: Preload queue cleared Jul 08 10:47:20 volumio3 volumio[1032]: info: Preloading song: music-library/USB/Apacer/Cassiopeia Station.m3u Jul 08 10:47:20 volumio3 volumio[1032]: info: Exploding uri music-library/USB/Apacer/Cassiopeia Station.m3u in service mpd Jul 08 10:47:21 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 08 10:47:21 volumio3 volumio[1032]: info: CURURI: music-library/USB/Apacer/Music Jul 08 10:47:21 volumio3 volumio[1032]: info: Preload queue cleared Jul 08 10:47:24 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 08 10:47:24 volumio3 volumio[1032]: info: CURURI: music-library/USB/Apacer/Music/[24-192] Jul 08 10:47:24 volumio3 volumio[1032]: info: Preload queue cleared Jul 08 10:47:32 volumio3 volumio[1032]: info: Preload queue cleared Jul 08 10:47:32 volumio3 volumio[1032]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 08 10:47:32 volumio3 volumio[1032]: info: CoreStateMachine::ClearQueue Jul 08 10:47:32 volumio3 volumio[1032]: info: CoreStateMachine::stop Jul 08 10:47:32 volumio3 volumio[1032]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 10:47:32 volumio3 volumio[1032]: info: CoreStateMachine::stPlaybackTimer Jul 08 10:47:32 volumio3 volumio[1032]: info: CoreStateMachine::updateTrackBlock Jul 08 10:47:32 volumio3 volumio[1032]: info: CorePlayQueue::getTrackBlock Jul 08 10:47:32 volumio3 volumio[1032]: info: CoreStateMachine::pushState Jul 08 10:47:32 volumio3 volumio[1032]: info: CorePlayQueue::getTrack 1 Jul 08 10:47:32 volumio3 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 10:47:32 volumio3 volumio[1032]: info: CoreCommandRouter::volumioPushState Jul 08 10:47:32 volumio3 volumio[1032]: info: CoreStateMachine::serviceStop Jul 08 10:47:32 volumio3 volumio[1032]: info: CorePlayQueue::getTrack 1 Jul 08 10:47:32 volumio3 volumio[1032]: info: CoreCommandRouter::serviceStop Jul 08 10:47:32 volumio3 volumio[1032]: info: ControllerMpd::stop Jul 08 10:47:32 volumio3 volumio[1032]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 10:47:32 volumio3 volumio[1032]: info: CorePlayQueue::clearPlayQueue Jul 08 10:47:32 volumio3 volumio[1032]: info: CorePlayQueue::saveQueue Jul 08 10:47:32 volumio3 volumio[1032]: info: CoreCommandRouter::volumioPushQueue Jul 08 10:47:32 volumio3 volumio[1032]: info: CoreStateMachine::addQueueItems Jul 08 10:47:32 volumio3 volumio[1032]: info: CorePlayQueue::addQueueItems Jul 08 10:47:32 volumio3 volumio[1032]: info: Preload queue cleared Jul 08 10:47:32 volumio3 volumio[1032]: info: Adding Item to queue: music-library/USB/Apacer/Music/[24-192]/Paris-France-Transit [24-192] Jul 08 10:47:32 volumio3 volumio[1032]: info: Exploding uri music-library/USB/Apacer/Music/[24-192]/Paris-France-Transit [24-192] in service mpd Jul 08 10:47:32 volumio3 volumio[1032]: info: ALBUMART /albumart?cacheid=384&web=Paris-France-Transit/Paris-France-Transit/extralarge&path=%2Fmnt%2FUSB%2FApacer%2FMusic%2F%5B24-192%5D%2FParis-France-Transit%20%5B24-192%5D%2FParis-France-Transit%20-%20Paris-France-Transit.cue&metadata=false Jul 08 10:47:32 volumio3 volumio[1032]: info: URI /mnt/USB/Apacer/Music/[24-192]/Paris-France-Transit [24-192]/Paris-France-Transit - Paris-France-Transit.cue Jul 08 10:47:32 volumio3 volumio[1032]: info: ALBUMART /albumart?cacheid=384&web=Paris-France-Transit/Paris-France-Transit/extralarge&path=%2Fmnt%2FUSB%2FApacer%2FMusic%2F%5B24-192%5D%2FParis-France-Transit%20%5B24-192%5D%2FParis-France-Transit%20-%20Paris-France-Transit.cue&metadata=false Jul 08 10:47:32 volumio3 volumio[1032]: info: URI /mnt/USB/Apacer/Music/[24-192]/Paris-France-Transit [24-192]/Paris-France-Transit - Paris-France-Transit.cue Jul 08 10:47:32 volumio3 volumio[1032]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 10:47:32 volumio3 volumio[1032]: Error: Unable to resolve or reject the same promise twice Jul 08 10:47:32 volumio3 volumio[1032]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jul 08 10:47:32 volumio3 volumio[1032]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Jul 08 10:47:32 volumio3 volumio[1032]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jul 08 10:47:32 volumio3 volumio[1032]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jul 08 10:47:32 volumio3 volumio[1032]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jul 08 10:47:32 volumio3 volumio[1032]: at Socket.emit (node:events:514:28) Jul 08 10:47:32 volumio3 volumio[1032]: at addChunk (node:internal/streams/readable:343:12) Jul 08 10:47:32 volumio3 volumio[1032]: at readableAddChunk (node:internal/streams/readable:312:11) Jul 08 10:47:32 volumio3 volumio[1032]: at Readable.push (node:internal/streams/readable:253:10) Jul 08 10:47:32 volumio3 volumio[1032]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jul 08 10:47:32 volumio3 volumio[1032]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 10:47:33 volumio3 sudo[4745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-08 10:46' Jul 08 10:47:33 volumio3 sudo[4745]: 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="4f980d5cf693b4272997a2fa2da8bb8036ddf1ab" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jul 5 02:50:33 UTC 2025" VOLUMIO_VERSION="4.015" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d42ab79cc13d24482826e04fda2e8350"