Aug 26 15:41:17 volumio ntpd[1197]: CLOCK: time stepped by 59351.031076 Aug 26 15:41:17 volumio ntpd[1197]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Aug 26 15:41:17 volumio volumio[1395]: info: Asound.conf file written Aug 26 15:41:17 volumio sudo[2291]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 26 15:41:17 volumio sudo[2291]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:41:17 volumio sudo[2291]: pam_unix(sudo:session): session closed for user root Aug 26 15:41:17 volumio volumio[1395]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 26 15:41:17 volumio volumio[1395]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 26 15:41:17 volumio volumio[1395]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 26 15:41:17 volumio volumio[1395]: info: Output device has changed, restarting MPD Aug 26 15:41:17 volumio sudo[2297]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 15:41:17 volumio sudo[2297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:41:17 volumio volumio[1395]: info: Output device has changed, restarting Shairport Sync Aug 26 15:41:17 volumio volumio[1395]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:41:17 volumio volumio[1395]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:41:17 volumio sudo[2297]: pam_unix(sudo:session): session closed for user root Aug 26 15:41:17 volumio sudo[2300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 15:41:17 volumio sudo[2300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:41:17 volumio volumio[1395]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 15:41:17 volumio volumio[1395]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 26 15:41:17 volumio volumio[1395]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:41:17 volumio volumio[1395]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Aug 26 15:41:17 volumio volumio[1395]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting Aug 26 15:41:17 volumio volumio[1395]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Aug 26 15:41:17 volumio volumio[1395]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Aug 26 15:41:17 volumio volumio[1395]: info: MRS: Pushing multiroomSync output for this device Aug 26 15:41:17 volumio volumio[1395]: info: MRS: Pushing multiroomSync output Aug 26 15:41:17 volumio volumio[1395]: info: Adding audio output: Aug 26 15:41:17 volumio volumio[1395]: info: Adding audio output: Aug 26 15:41:17 volumio volumio[1395]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 26 15:41:17 volumio volumio[1395]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:41:17 volumio volumio[1395]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Aug 26 15:41:17 volumio volumio[1395]: info: Adding METAVOLUMIO REST API Endpoints Aug 26 15:41:17 volumio volumio[1395]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Aug 26 15:41:17 volumio volumio[1395]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Aug 26 15:41:17 volumio volumio[1395]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Aug 26 15:41:17 volumio volumio[1395]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Aug 26 15:41:17 volumio volumio[1395]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Aug 26 15:41:17 volumio volumio[1395]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Aug 26 15:41:17 volumio volumio[1395]: info: Preparing CD Folders Aug 26 15:41:17 volumio volumio[1395]: info: Adding CD REST API Endpoints Aug 26 15:41:17 volumio volumio[1395]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Aug 26 15:41:17 volumio volumio[1395]: info: Starting UDEV Watcher for CD Aug 26 15:41:17 volumio volumio[1395]: info: Detecting CD presence with UDEV Aug 26 15:41:17 volumio volumio[1395]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Aug 26 15:41:18 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Aug 26 15:41:18 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Aug 26 15:41:18 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 26 15:41:18 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Aug 26 15:41:18 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Aug 26 15:41:18 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Aug 26 15:41:18 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 26 15:41:18 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 26 15:41:18 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 26 15:41:18 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 26 15:41:18 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 26 15:41:18 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 26 15:41:18 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 26 15:41:18 volumio sudo[2329]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 15:41:18 volumio sudo[2329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 26 15:41:18 volumio sudo[2329]: pam_unix(sudo:session): session closed for user root Aug 26 15:41:18 volumio mpd[2331]: 2026-08-26T15:41:18 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 26 15:41:18 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 26 15:41:18 volumio sudo[2300]: pam_unix(sudo:session): session closed for user root Aug 26 15:41:18 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Aug 26 15:41:18 volumio sudo[2202]: pam_unix(sudo:session): session closed for user root Aug 26 15:41:23 volumio volumio[1395]: warn: [cd-plugin] cdspeedctl: device or media not ready Aug 26 15:41:23 volumio volumio[1395]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Aug 26 15:41:23 volumio volumio[1395]: info: Adding inputs REST Endpoints Aug 26 15:41:23 volumio volumio[1395]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Aug 26 15:41:23 volumio volumio[1395]: info: Scanning Audio Inputs Aug 26 15:41:23 volumio volumio[1395]: info: Checking against Known Cards name Aug 26 15:41:23 volumio volumio[1395]: info: Adding Server instance for streaming Aug 26 15:41:23 volumio volumio[1395]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Aug 26 15:41:23 volumio volumio[1395]: error: Hi Res Audio Failed Login: Missing Login Data Aug 26 15:41:23 volumio volumio[1395]: info: Adding HIGHRESAUDIO REST API Endpoints Aug 26 15:41:23 volumio volumio[1395]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 26 15:41:23 volumio volumio[1395]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Aug 26 15:41:23 volumio volumio[1395]: info: Refreshing TIDAL token Aug 26 15:41:23 volumio volumio[1395]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Aug 26 15:41:23 volumio volumio[1395]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Aug 26 15:41:23 volumio volumio[1395]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Aug 26 15:41:23 volumio volumio[1395]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Aug 26 15:41:23 volumio sudo[2341]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 26 15:41:23 volumio sudo[2341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:41:23 volumio volumio[1395]: info: QobuzConnect: Starting Qobuz Connect socket and service Aug 26 15:41:23 volumio volumio[1395]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Aug 26 15:41:23 volumio volumio[1395]: info: Stopping AccessToken refresher cron for QOBUZ Aug 26 15:41:23 volumio sudo[2348]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 26 15:41:23 volumio sudo[2348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:41:23 volumio sudo[2341]: pam_unix(sudo:session): session closed for user root Aug 26 15:41:23 volumio volumio[1395]: info: AccessToken refresher cron started for QOBUZ Aug 26 15:41:23 volumio volumio[1395]: info: Adding QOBUZ REST API Endpoints Aug 26 15:41:23 volumio volumio[1395]: info: MRS: Getting audio outputs on start Aug 26 15:41:23 volumio volumio[1395]: info: MRS: Requesting all other devices output Aug 26 15:41:23 volumio sudo[2348]: pam_unix(sudo:session): session closed for user root Aug 26 15:41:23 volumio volumio[1395]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 15:41:23 volumio volumio[1395]: info: Systeminfo: --[object Object] Aug 26 15:41:23 volumio volumio[1395]: info: Systeminfo web UI listening on port 12334 Aug 26 15:41:23 volumio volumio[1395]: info: Setting Geolocation for MyVolumio to au1 Aug 26 15:41:23 volumio volumio[1395]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:41:23 volumio volumio[1395]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:41:23 volumio volumio[1395]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:41:23 volumio sudo[2351]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 26 15:41:23 volumio sudo[2351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:41:23 volumio volumio[1395]: info: Setting Geolocation for MyVolumio to au1 Aug 26 15:41:23 volumio volumio[1395]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:41:23 volumio volumio[1395]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:41:23 volumio volumio[1395]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:41:23 volumio volumio[1395]: error: Failed to ping endpoint au1.myvolumio.org : unknown error Aug 26 15:41:23 volumio volumio[1395]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 15:41:23 volumio volumio[1395]: Error: Unable to resolve or reject the same promise twice Aug 26 15:41:23 volumio volumio[1395]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 26 15:41:23 volumio volumio[1395]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Aug 26 15:41:23 volumio volumio[1395]: at Socket.emit (node:events:514:28) Aug 26 15:41:23 volumio volumio[1395]: at addChunk (node:internal/streams/readable:343:12) Aug 26 15:41:23 volumio volumio[1395]: at readableAddChunk (node:internal/streams/readable:316:9) Aug 26 15:41:23 volumio volumio[1395]: at Readable.push (node:internal/streams/readable:253:10) Aug 26 15:41:23 volumio volumio[1395]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Aug 26 15:41:23 volumio volumio[1395]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 15:41:23 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 26 15:41:23 volumio sudo[2351]: pam_unix(sudo:session): session closed for user root Aug 26 15:41:23 volumio sudo[2368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-26 15:40' Aug 26 15:41:23 volumio sudo[2368]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"