Aug 31 17:12:01 volumio1 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 31 17:12:03 volumio1 systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 31 17:12:03 volumio1 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 31 17:12:08 volumio1 volumio[1242]: info: CALLMETHOD: system_hardware wireless_output_manager scanDevices Aug 31 17:12:08 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: wireless_output_manager , scanDevices Aug 31 17:12:08 volumio1 volumio[1242]: info: [Wireless Output Manager][Bluetooth] Scanning for devices Aug 31 17:12:08 volumio1 volumio[1242]: info: [Wireless Output Manager][Bluetooth] Starting a 12 second scan Aug 31 17:12:08 volumio1 bluetoothd[899]: Path / reserved for Adv Monitor app :1.1025 Aug 31 17:12:08 volumio1 bluealsa[1049]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4F_07_33_9F_6A_0A, ...) Aug 31 17:12:08 volumio1 kernel: Bluetooth: hci0: Opcode 0x2005 failed: -16 Aug 31 17:12:09 volumio1 bluealsa[1049]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_7A_12_34_DA_48_DD, ...) Aug 31 17:12:09 volumio1 bluealsa[1049]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4A_38_7B_B8_72_1A, ...) Aug 31 17:12:09 volumio1 bluealsa[1049]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_4D_A0_D6_E5_49_7D, ...) Aug 31 17:12:09 volumio1 bluealsa[1049]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_48_28_10_A3_CE_3F, ...) Aug 31 17:12:09 volumio1 bluealsa[1049]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_5C_50_C1_26_F3_C4, ...) Aug 31 17:12:10 volumio1 bluealsa[1049]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_5C_F9_38_D1_8A_E9, ...) Aug 31 17:12:21 volumio1 bluetoothd[899]: Adv Monitor app :1.1025 disconnected from D-Bus Aug 31 17:12:21 volumio1 bluetoothd[899]: Path / reserved for Adv Monitor app :1.1091 Aug 31 17:12:21 volumio1 bluetoothd[899]: Adv Monitor app :1.1091 disconnected from D-Bus Aug 31 17:12:39 volumio1 bluealsa[1049]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_4F_07_33_9F_6A_0A, ...) Aug 31 17:12:39 volumio1 bluealsa[1049]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_7A_12_34_DA_48_DD, ...) Aug 31 17:12:39 volumio1 bluealsa[1049]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_4A_38_7B_B8_72_1A, ...) Aug 31 17:12:39 volumio1 bluealsa[1049]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_4D_A0_D6_E5_49_7D, ...) Aug 31 17:12:40 volumio1 bluealsa[1049]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_48_28_10_A3_CE_3F, ...) Aug 31 17:12:40 volumio1 bluealsa[1049]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_5C_50_C1_26_F3_C4, ...) Aug 31 17:12:40 volumio1 bluealsa[1049]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_5C_F9_38_D1_8A_E9, ...) Aug 31 17:13:33 volumio1 volumio[1242]: info: CoreCommandRouter::volumioGetState Aug 31 17:13:33 volumio1 volumio[1242]: info: CorePlayQueue::getTrack 0 Aug 31 17:13:35 volumio1 volumio[1242]: info: Executing endpoint metavolumio Aug 31 17:13:35 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 17:13:35 volumio1 volumio[1242]: info: Executing endpoint metavolumio Aug 31 17:13:35 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 17:13:35 volumio1 volumio[1242]: info: Executing endpoint metavolumio Aug 31 17:13:35 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 17:13:36 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 17:13:36 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 31 17:13:40 volumio1 volumio[1242]: info: [Wireless Output Manager] Stopping Aug 31 17:13:40 volumio1 volumio[1242]: info: Disabling plugin wireless_output_manager Aug 31 17:13:40 volumio1 volumio[1242]: info: Preparing to generate the ALSA configuration file Aug 31 17:13:40 volumio1 volumio[1242]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 31 17:13:40 volumio1 volumio[1242]: info: Reading ALSA contributions from plugins. Aug 31 17:13:40 volumio1 volumio[1242]: info: Asound.conf file unchanged, so no further update is needed Aug 31 17:13:40 volumio1 volumio[1242]: info: Output device has changed, restarting MPD Aug 31 17:13:40 volumio1 volumio[1242]: info: Output device has changed, restarting Shairport Sync Aug 31 17:13:40 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:40 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 17:13:40 volumio1 sudo[6274]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 17:13:40 volumio1 sudo[6274]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 17:13:40 volumio1 sudo[6276]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 17:13:40 volumio1 sudo[6274]: pam_unix(sudo:session): session closed for user root Aug 31 17:13:40 volumio1 sudo[6276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 17:13:40 volumio1 volumio[1242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 17:13:40 volumio1 volumio[1242]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 31 17:13:40 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:40 volumio1 volumio[1242]: info: QobuzConnect: setDeactiveState invoked Aug 31 17:13:40 volumio1 volumio[1242]: info: CoreCommandRouter::volumioGetState Aug 31 17:13:40 volumio1 volumio[1242]: info: CorePlayQueue::getTrack 0 Aug 31 17:13:40 volumio1 vtcs[2047]: [2026-08-31 17:13:40.284] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Aug 31 17:13:40 volumio1 systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 31 17:13:40 volumio1 vtcs[2047]: [2026-08-31 17:13:40.289] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Aug 31 17:13:40 volumio1 volumio[1242]: info: Done. Aug 31 17:13:40 volumio1 sudo[6286]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 17:13:40 volumio1 sudo[6286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 17:13:40 volumio1 systemd[1]: mpd.service: Deactivated successfully. Aug 31 17:13:40 volumio1 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 17:13:40 volumio1 systemd[1]: mpd.service: Consumed 1.445s CPU time. Aug 31 17:13:40 volumio1 systemd[1]: mpd.socket: Deactivated successfully. Aug 31 17:13:40 volumio1 sudo[6289]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 17:13:40 volumio1 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 17:13:40 volumio1 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 17:13:40 volumio1 sudo[6289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 17:13:40 volumio1 volumio[1242]: info: MPD Permissions set Aug 31 17:13:40 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:40 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:40 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:40 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:40 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:40 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:40 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:40 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:40 volumio1 sudo[6299]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 31 17:13:40 volumio1 sudo[6299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 17:13:40 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 17:13:40 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:40 volumio1 volumio[1242]: info: Starting Shairport Sync Aug 31 17:13:40 volumio1 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 17:13:40 volumio1 systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 17:13:40 volumio1 sudo[6308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 17:13:40 volumio1 sudo[6308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 17:13:40 volumio1 sudo[6299]: pam_unix(sudo:session): session closed for user root Aug 31 17:13:40 volumio1 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 31 17:13:40 volumio1 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Aug 31 17:13:40 volumio1 systemd[1]: vtcs.service: Killing process 2059 (vtcs) with signal SIGKILL. Aug 31 17:13:40 volumio1 systemd[1]: shairport-sync.service: Deactivated successfully. Aug 31 17:13:40 volumio1 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 17:13:40 volumio1 systemd[1]: shairport-sync.service: Consumed 6.501s CPU time. Aug 31 17:13:40 volumio1 sudo[6310]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 31 17:13:40 volumio1 sudo[6310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 17:13:40 volumio1 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 17:13:40 volumio1 systemd[1]: vtcs.service: Deactivated successfully. Aug 31 17:13:40 volumio1 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 31 17:13:40 volumio1 systemd[1]: vtcs.service: Consumed 1.019s CPU time. Aug 31 17:13:40 volumio1 sudo[6308]: pam_unix(sudo:session): session closed for user root Aug 31 17:13:40 volumio1 volumio[1242]: info: Shairport-Sync Started Aug 31 17:13:40 volumio1 sudo[6286]: pam_unix(sudo:session): session closed for user root Aug 31 17:13:40 volumio1 sudo[6289]: pam_unix(sudo:session): session closed for user root Aug 31 17:13:40 volumio1 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Aug 31 17:13:40 volumio1 qobuz-connect[1981]: 20260831 17:13:40.532 [1981.1981] INFO SampleApp: Stopping Local configuration server Aug 31 17:13:40 volumio1 sudo[6306]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 17:13:40 volumio1 sudo[6306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 17:13:40 volumio1 sudo[6306]: pam_unix(sudo:session): session closed for user root Aug 31 17:13:41 volumio1 mpd[6330]: 2026-08-31T17:13:41 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 17:13:41 volumio1 systemd[1]: Started mpd.service - Music Player Daemon. Aug 31 17:13:41 volumio1 sudo[6276]: pam_unix(sudo:session): session closed for user root Aug 31 17:13:41 volumio1 volumio[1242]: error: updateQueue error: null Aug 31 17:13:42 volumio1 qobuz-connect[1981]: 20260831 17:13:42.347 [1981.1981] INFO SampleApp: shat down connection on UNIX socket Aug 31 17:13:42 volumio1 volumio[1242]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Aug 31 17:13:42 volumio1 volumio[1242]: info: QobuzConnect: setDeactiveState invoked Aug 31 17:13:42 volumio1 volumio[1242]: info: CoreCommandRouter::volumioGetState Aug 31 17:13:42 volumio1 volumio[1242]: info: CorePlayQueue::getTrack 0 Aug 31 17:13:42 volumio1 systemd[1]: qobuz-connect.service: Deactivated successfully. Aug 31 17:13:42 volumio1 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Aug 31 17:13:42 volumio1 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 31 17:13:42 volumio1 sudo[6310]: pam_unix(sudo:session): session closed for user root Aug 31 17:13:42 volumio1 volumio[1242]: info: Executing endpoint qc_getconfig Aug 31 17:13:42 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 31 17:13:42 volumio1 qobuz-connect[6335]: 20260831 17:13:42.455 [6335.6335] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 31 17:13:42 volumio1 volumio[1242]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Aug 31 17:13:42 volumio1 volumio[1242]: info: QobuzConnect: QOBUZ Connect daemon connected Aug 31 17:13:42 volumio1 qobuz-connect[6335]: 20260831 17:13:42.459 [6335.6335] INFO VolumeManager: [0x11a7e68]: Setting new playback volume: 75 Aug 31 17:13:42 volumio1 qobuz-connect[6335]: 20260831 17:13:42.459 [6335.6335] INFO VolumeManager: [0x11a7e68]: Setting new mute state: 0 Aug 31 17:13:42 volumio1 qobuz-connect[6335]: 20260831 17:13:42.459 [6335.6335] INFO AudioStreamManager: [0x11a7bc0]: Setting new audio download buffer size: 1048576 Aug 31 17:13:42 volumio1 qobuz-connect[6335]: 20260831 17:13:42.459 [6335.6335] INFO QobuzConnect: [0x11a8730]: Client initialized! Aug 31 17:13:42 volumio1 qobuz-connect[6335]: 20260831 17:13:42.459 [6335.6335] INFO SampleApp: Starting Avahi advertising, name: Volumio1, service name: _qobuz-connect._tcp Aug 31 17:13:42 volumio1 qobuz-connect[6335]: 20260831 17:13:42.468 [6335.6335] INFO LocalConfigManager: [0x11a78e8]: Starting Local Configuration server Aug 31 17:13:42 volumio1 qobuz-connect[6335]: 20260831 17:13:42.468 [6335.6335] INFO SampleApp: Starting Local configuration server Aug 31 17:13:42 volumio1 qobuz-connect[6335]: 20260831 17:13:42.468 [6335.6335] INFO SampleApp: Connected to UNIX socket client 0x1192818 Aug 31 17:13:42 volumio1 qobuz-connect[6335]: 20260831 17:13:42.605 [6335.6335] INFO SampleApp: Playback volume changed: 75 Aug 31 17:13:42 volumio1 volumio[1242]: info: CoreCommandRouter::volumioGetState Aug 31 17:13:42 volumio1 volumio[1242]: info: CorePlayQueue::getTrack 0 Aug 31 17:13:43 volumio1 volumio[1242]: info: Starting Uninstall of plugin system_hardware - wireless_output_manager Aug 31 17:13:43 volumio1 volumio[1242]: info: Uninstalling plugin wireless_output_manager Aug 31 17:13:43 volumio1 volumio[1242]: info: Disabling plugin wireless_output_manager Aug 31 17:13:43 volumio1 volumio[1242]: info: Preparing to generate the ALSA configuration file Aug 31 17:13:43 volumio1 volumio5-onboarding[1578]: time=2026-08-31T17:13:43.185+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 31 17:13:43 volumio1 volumio[1242]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 31 17:13:43 volumio1 volumio[1242]: info: Reading ALSA contributions from plugins. Aug 31 17:13:43 volumio1 volumio[1242]: info: Asound.conf file unchanged, so no further update is needed Aug 31 17:13:43 volumio1 volumio[1242]: info: Output device has changed, restarting MPD Aug 31 17:13:43 volumio1 sudo[6342]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 17:13:43 volumio1 sudo[6342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 17:13:43 volumio1 volumio[1242]: info: Output device has changed, restarting Shairport Sync Aug 31 17:13:43 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:43 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 17:13:43 volumio1 sudo[6342]: pam_unix(sudo:session): session closed for user root Aug 31 17:13:43 volumio1 sudo[6345]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 17:13:43 volumio1 sudo[6345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 17:13:43 volumio1 volumio[1242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 17:13:43 volumio1 volumio[1242]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 31 17:13:43 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:43 volumio1 volumio[1242]: info: QobuzConnect: setDeactiveState invoked Aug 31 17:13:43 volumio1 volumio[1242]: info: CoreCommandRouter::volumioGetState Aug 31 17:13:43 volumio1 volumio[1242]: info: CorePlayQueue::getTrack 0 Aug 31 17:13:43 volumio1 systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 31 17:13:43 volumio1 volumio5-onboarding[1578]: time=2026-08-31T17:13:43.278+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 31 17:13:43 volumio1 volumio[1242]: info: Checking if uninstall.sh is present Aug 31 17:13:43 volumio1 sudo[6354]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 17:13:43 volumio1 sudo[6354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 17:13:43 volumio1 systemd[1]: mpd.service: Deactivated successfully. Aug 31 17:13:43 volumio1 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 17:13:43 volumio1 systemd[1]: mpd.service: Consumed 1.423s CPU time. Aug 31 17:13:43 volumio1 systemd[1]: mpd.socket: Deactivated successfully. Aug 31 17:13:43 volumio1 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 17:13:43 volumio1 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 17:13:43 volumio1 sudo[6357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 17:13:43 volumio1 sudo[6357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 17:13:43 volumio1 volumio[1242]: info: Executing uninstall.sh Aug 31 17:13:43 volumio1 volumio[1242]: info: MPD Permissions set Aug 31 17:13:43 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:43 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:43 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:43 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:43 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:43 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:43 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:43 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:43 volumio1 sudo[6366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 31 17:13:43 volumio1 sudo[6366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 17:13:43 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 17:13:43 volumio1 volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 17:13:43 volumio1 volumio[1242]: info: Starting Shairport Sync Aug 31 17:13:43 volumio1 sudo[6369]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/system_hardware/wireless_output_manager/uninstall.sh Aug 31 17:13:43 volumio1 sudo[6369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 17:13:43 volumio1 sudo[6379]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 17:13:43 volumio1 sudo[6379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 17:13:43 volumio1 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 17:13:43 volumio1 systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 17:13:43 volumio1 sudo[6366]: pam_unix(sudo:session): session closed for user root Aug 31 17:13:43 volumio1 systemd[1]: Reloading. Aug 31 17:13:43 volumio1 sudo[6390]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 31 17:13:43 volumio1 sudo[6390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 17:13:43 volumio1 volumio[1242]: info: TidalConnect service stoped! Aug 31 17:13:43 volumio1 volumio[1242]: info: TidalConnect service stoped! Aug 31 17:13:43 volumio1 sudo[6388]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 17:13:43 volumio1 sudo[6388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 17:13:43 volumio1 sudo[6388]: pam_unix(sudo:session): session closed for user root Aug 31 17:13:43 volumio1 volumio[1242]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 31 17:13:43 volumio1 volumio[1242]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 31 17:13:43 volumio1 sudo[6403]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 31 17:13:43 volumio1 sudo[6403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 17:13:44 volumio1 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 31 17:13:44 volumio1 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 31 17:13:44 volumio1 sudo[6354]: pam_unix(sudo:session): session closed for user root Aug 31 17:13:44 volumio1 sudo[6357]: pam_unix(sudo:session): session closed for user root Aug 31 17:13:44 volumio1 volumio[1242]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 17:13:44 volumio1 volumio[1242]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Aug 31 17:13:44 volumio1 volumio[1242]: Job for vtcs.service canceled. Aug 31 17:13:44 volumio1 volumio[1242]: at ChildProcess.exithandler (node:child_process:421:12) Aug 31 17:13:44 volumio1 volumio[1242]: at ChildProcess.emit (node:events:514:28) Aug 31 17:13:44 volumio1 volumio[1242]: at maybeClose (node:internal/child_process:1105:16) Aug 31 17:13:44 volumio1 volumio[1242]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) { Aug 31 17:13:44 volumio1 volumio[1242]: code: 1, Aug 31 17:13:44 volumio1 volumio[1242]: killed: false, Aug 31 17:13:44 volumio1 volumio[1242]: signal: null, Aug 31 17:13:44 volumio1 volumio[1242]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Aug 31 17:13:44 volumio1 volumio[1242]: stdout: '', Aug 31 17:13:44 volumio1 volumio[1242]: stderr: 'Job for vtcs.service canceled.\n' Aug 31 17:13:44 volumio1 volumio[1242]: } Aug 31 17:13:44 volumio1 volumio[1242]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 17:13:44 volumio1 systemd[1]: Stopping bluealsa.service - BlueALSA service... Aug 31 17:13:44 volumio1 bluealsa[1049]: ../src/ba-adapter.c:144: Freeing adapter: hci0 Aug 31 17:13:44 volumio1 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Aug 31 17:13:44 volumio1 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 31 17:13:44 volumio1 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 31 17:13:44 volumio1 bluetoothd[899]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/aptXHD/sink/1 Aug 31 17:13:44 volumio1 sudo[6403]: pam_unix(sudo:session): session closed for user root Aug 31 17:13:44 volumio1 systemd[1]: bluealsa.service: Deactivated successfully. Aug 31 17:13:44 volumio1 systemd[1]: Stopped bluealsa.service - BlueALSA service. Aug 31 17:13:44 volumio1 qobuz-connect[6335]: 20260831 17:13:44.551 [6335.6335] INFO SampleApp: Stopping Local configuration server Aug 31 17:13:44 volumio1 systemd[1]: shairport-sync.service: Deactivated successfully. Aug 31 17:13:44 volumio1 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 17:13:44 volumio1 systemd[1]: shairport-sync.service: Consumed 1.565s CPU time. Aug 31 17:13:44 volumio1 bluetoothd[899]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/LDAC/source/1 Aug 31 17:13:44 volumio1 bluetoothd[899]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/aptX/sink/2 Aug 31 17:13:44 volumio1 systemd[1]: Starting bluealsa.service - BlueALSA service... Aug 31 17:13:44 volumio1 bluetoothd[899]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/source/2 Aug 31 17:13:44 volumio1 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 17:13:44 volumio1 bluetoothd[899]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/aptX/sink/1 Aug 31 17:13:44 volumio1 bluetoothd[899]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/source/1 Aug 31 17:13:44 volumio1 sudo[6379]: pam_unix(sudo:session): session closed for user root Aug 31 17:13:44 volumio1 bluetoothd[899]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/sink/2 Aug 31 17:13:44 volumio1 bluetoothd[899]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/aptXHD/source/2 Aug 31 17:13:44 volumio1 bluetoothd[899]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/sink/1 Aug 31 17:13:44 volumio1 bluetoothd[899]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/aptX/source/2 Aug 31 17:13:44 volumio1 bluetoothd[899]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/aptXHD/source/1 Aug 31 17:13:44 volumio1 bluetoothd[899]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/aptXHD/sink/2 Aug 31 17:13:44 volumio1 bluetoothd[899]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/LDAC/source/2 Aug 31 17:13:44 volumio1 bluetoothd[899]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/aptX/source/1 Aug 31 17:13:44 volumio1 bluealsa[6440]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa Aug 31 17:13:44 volumio1 bluealsa[6440]: main.c:663: Starting main dispatching loop Aug 31 17:13:44 volumio1 systemd[1]: Started bluealsa.service - BlueALSA service. Aug 31 17:13:44 volumio1 bluealsa[6440]: main.c:118: Acquired D-Bus service name: org.bluealsa Aug 31 17:13:44 volumio1 bluealsa[6440]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa Aug 31 17:13:44 volumio1 bluealsa[6440]: bluez.c:153: Registering media application: /org/bluez/hci0 Aug 31 17:13:44 volumio1 bluealsa[6440]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 Aug 31 17:13:44 volumio1 bluealsa[6440]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 Aug 31 17:13:44 volumio1 bluealsa[6440]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 Aug 31 17:13:44 volumio1 bluealsa[6440]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 Aug 31 17:13:44 volumio1 sudo[6369]: pam_unix(sudo:session): session closed for user root Aug 31 17:13:44 volumio1 bluetoothd[899]: Endpoint registered: sender=:1.1097 path=/org/bluez/hci0/A2DP/SBC/source/1 Aug 31 17:13:44 volumio1 bluetoothd[899]: Endpoint registered: sender=:1.1097 path=/org/bluez/hci0/A2DP/SBC/sink/2 Aug 31 17:13:44 volumio1 bluetoothd[899]: Endpoint registered: sender=:1.1097 path=/org/bluez/hci0/A2DP/SBC/source/2 Aug 31 17:13:44 volumio1 bluetoothd[899]: Endpoint registered: sender=:1.1097 path=/org/bluez/hci0/A2DP/SBC/sink/1 Aug 31 17:13:45 volumio1 sudo[6462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 17:12' Aug 31 17:13:45 volumio1 sudo[6462]: 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"