Aug 27 11:27:09 localhost systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 27 11:27:09 localhost systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 27 11:27:09 localhost systemd[1]: setdatetime-helper.service: Consumed 1.134s CPU time. Aug 27 11:27:09 localhost systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Aug 27 11:27:09 localhost systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Aug 27 11:27:09 localhost systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Aug 27 11:27:09 localhost systemd[1]: e2scrub_all.service: Deactivated successfully. Aug 27 11:27:09 localhost systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Aug 27 11:27:09 localhost systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Aug 27 11:27:09 localhost sudo[971]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:09 localhost ntpd[769]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 27 11:27:09 localhost wireless.js[533]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: DE Aug 27 11:27:09 localhost sudo[1101]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Aug 27 11:27:09 localhost sudo[1101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:09 localhost ntpd[769]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Aug 27 11:27:09 localhost ntpd[769]: DNS: Pool taking: 37.247.53.178 Aug 27 11:27:09 localhost ntpd[769]: DNS: Pool taking: 81.56.230.156 Aug 27 11:27:09 localhost ntpd[769]: DNS: Pool taking: 162.159.200.123 Aug 27 11:27:09 localhost ntpd[769]: DNS: Pool taking: 31.207.113.74 Aug 27 11:27:09 localhost ntpd[769]: DNS: Pool taking: 2a00:6d41:10:1194::1 Aug 27 11:27:09 localhost ntpd[769]: DNS: Pool taking: 2a00:6d41:10:1194::6 Aug 27 11:27:09 localhost ntpd[769]: DNS: Pool taking: 2a03:b020:0:403::254 Aug 27 11:27:09 localhost ntpd[769]: DNS: Pool taking: 2603:c020:8017:3e00::123:123 Aug 27 11:27:09 localhost ntpd[769]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Aug 27 11:27:09 localhost systemd[1]: dpkg-db-backup.service: Deactivated successfully. Aug 27 11:27:09 localhost systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Aug 27 11:27:09 localhost sudo[1101]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:09 localhost wireless.js[533]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: DE Aug 27 11:27:09 localhost wireless.js[533]: WIRELESS.JS - INFO: netconfigured file not found, starting hotspot Aug 27 11:27:09 localhost wireless.js[533]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Aug 27 11:27:09 localhost wireless.js[533]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Aug 27 11:27:09 localhost wireless.js[533]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Aug 27 11:27:09 localhost winbindd[1008]: [2026/08/27 11:27:09.417383, 0] ../../source3/winbindd/winbindd_idmap.c:372(wb_parent_idmap_setup_lookupname_done) Aug 27 11:27:09 localhost winbindd[1008]: wb_parent_idmap_setup_lookupname_done: Lookup domain name 'STREAMPLAY' failed 'NT_STATUS_IO_TIMEOUT' Aug 27 11:27:09 localhost systemd[1]: Started smbd.service - Samba SMB Daemon. Aug 27 11:27:09 localhost sudo[1116]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 27 11:27:09 localhost sudo[1116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 11:27:09 localhost sudo[1116]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:09 localhost sudo[1119]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 27 11:27:09 localhost sudo[1119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 11:27:09 localhost sudo[1119]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:09 localhost wpa_supplicant[1122]: Successfully initialized wpa_supplicant Aug 27 11:27:09 localhost wpa_supplicant[1122]: nl80211: kernel reports: Registration to specific type not supported Aug 27 11:27:09 localhost wireless.js[533]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 1895245530ms Aug 27 11:27:09 localhost wireless.js[533]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Aug 27 11:27:09 localhost wireless.js[533]: Failed to connect to non-global ctrl_ifname: wlan0 error: No such file or directory Aug 27 11:27:09 localhost wireless.js[533]: WIRELESS.JS - INFO: Notified systemd about wireless ready Aug 27 11:27:09 localhost iwconfig[1144]: Error for wireless request "Set Power Management" (8B2C) : Aug 27 11:27:09 localhost iwconfig[1144]: SET failed on device wlan0 ; Operation not supported. Aug 27 11:27:09 localhost systemd[1]: Started wireless.service - Wireless Services. Aug 27 11:27:09 localhost systemd[1]: Started volumio.service - Volumio Backend Module. Aug 27 11:27:09 localhost systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Aug 27 11:27:09 localhost systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Aug 27 11:27:10 localhost systemd[1]: Received SIGRTMIN+21 from PID 478 (plymouthd). Aug 27 11:27:10 localhost systemd[1]: Received SIGRTMIN+21 from PID 478 (plymouthd). Aug 27 11:27:10 localhost systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Aug 27 11:27:10 localhost systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Aug 27 11:27:10 localhost systemd[1]: Started getty@tty1.service - Getty on tty1. Aug 27 11:27:10 localhost systemd[1]: Reached target getty.target - Login Prompts. Aug 27 11:27:10 localhost systemd[1]: Reached target multi-user.target - Multi-User System. Aug 27 11:27:10 localhost systemd[1]: Reached target graphical.target - Graphical Interface. Aug 27 11:27:10 localhost systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Aug 27 11:27:10 localhost volumio-cpu-tweak[1147]: Setting RT Priority for mpd Aug 27 11:27:10 localhost volumio-cpu-tweak[1163]: pid 35's current scheduling policy: SCHED_OTHER Aug 27 11:27:10 localhost volumio-cpu-tweak[1163]: pid 35's current scheduling priority: 0 Aug 27 11:27:10 localhost volumio-cpu-tweak[1147]: Setting MPD Affinity Aug 27 11:27:10 localhost volumio-cpu-tweak[1164]: pid 3's current affinity mask: f Aug 27 11:27:10 localhost volumio-cpu-tweak[1147]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Aug 27 11:27:10 localhost volumio-cpu-tweak[1147]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Aug 27 11:27:10 localhost systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Aug 27 11:27:10 localhost systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Aug 27 11:27:10 localhost systemd[1]: Startup finished in 20.485s (kernel) + 16.327s (userspace) = 36.813s. Aug 27 11:27:10 localhost systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Aug 27 11:27:10 localhost ntpd[769]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 27 11:27:10 localhost ntpd[769]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Aug 27 11:27:10 localhost ntpd[769]: DNS: Pool taking: 195.32.70.195 Aug 27 11:27:10 localhost ntpd[769]: DNS: Pool taking: 151.22.209.90 Aug 27 11:27:10 localhost ntpd[769]: DNS: Pool taking: 93.94.88.51 Aug 27 11:27:10 localhost ntpd[769]: DNS: Pool taking: 95.110.254.234 Aug 27 11:27:10 localhost ntpd[769]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Aug 27 11:27:11 localhost ntpd[769]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 27 11:27:11 localhost ntpd[769]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Aug 27 11:27:11 localhost ntpd[769]: DNS: Pool taking: 95.110.135.141 Aug 27 11:27:11 localhost ntpd[769]: DNS: Pool taking: 172.232.209.103 Aug 27 11:27:11 localhost ntpd[769]: DNS: Pool taking: 185.157.229.254 Aug 27 11:27:11 localhost ntpd[769]: DNS: Pool taking: 89.46.74.148 Aug 27 11:27:11 localhost ntpd[769]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Aug 27 11:27:12 localhost volumio-remote-updater[530]: [2026-08-27 11:27:12] [connect] Successful connection Aug 27 11:27:13 localhost volumio[1145]: info: ------------------------------------------- Aug 27 11:27:13 localhost volumio[1145]: info: ----- Volumio3 ---- Aug 27 11:27:13 localhost volumio[1145]: info: ------------------------------------------- Aug 27 11:27:13 localhost volumio[1145]: info: ----- System startup ---- Aug 27 11:27:13 localhost volumio[1145]: info: ------------------------------------------- Aug 27 11:27:14 localhost volumio[1145]: info: MYVOLUMIO Environment detected Aug 27 11:27:16 localhost ntpd[769]: CLOCK: time stepped by 0.814288 Aug 27 11:27:16 localhost ntpd[769]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Aug 27 11:27:16 localhost volumio[1145]: info: Plugin folders cleanup Aug 27 11:27:16 localhost volumio[1145]: info: Scanning into folder /volumio/app/plugins/ Aug 27 11:27:16 localhost volumio[1145]: info: Scanning category audio_interface Aug 27 11:27:16 localhost volumio[1145]: info: Scanning category miscellanea Aug 27 11:27:16 localhost volumio[1145]: info: Scanning category music_service Aug 27 11:27:16 localhost volumio[1145]: info: Scanning category plugins.json Aug 27 11:27:16 localhost volumio[1145]: info: Scanning category system_controller Aug 27 11:27:16 localhost volumio[1145]: info: Scanning category user_interface Aug 27 11:27:16 localhost volumio[1145]: info: Scanning into folder /data/plugins/ Aug 27 11:27:16 localhost volumio[1145]: info: Plugin folders cleanup completed Aug 27 11:27:16 localhost volumio[1145]: info: ------------------------------------------- Aug 27 11:27:16 localhost volumio[1145]: info: ----- Core plugins startup ---- Aug 27 11:27:16 localhost volumio[1145]: info: ------------------------------------------- Aug 27 11:27:16 localhost volumio[1145]: info: Loading plugins from folder /volumio/app/plugins/ Aug 27 11:27:16 localhost volumio[1145]: info: Adding plugin bluetooth to MyMusic Plugins Aug 27 11:27:16 localhost volumio[1145]: info: Adding plugin multiroom to MyMusic Plugins Aug 27 11:27:16 localhost volumio[1145]: info: Adding plugin upnp to MyMusic Plugins Aug 27 11:27:16 localhost volumio[1145]: info: Adding plugin metavolumio to MyMusic Plugins Aug 27 11:27:16 localhost volumio[1145]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 27 11:27:16 localhost volumio[1145]: info: Adding plugin cd_controller to MyMusic Plugins Aug 27 11:27:16 localhost volumio[1145]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 27 11:27:16 localhost volumio[1145]: info: Adding plugin raat to MyMusic Plugins Aug 27 11:27:16 localhost volumio[1145]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 27 11:27:16 localhost volumio[1145]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 27 11:27:16 localhost volumio[1145]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 27 11:27:16 localhost volumio[1145]: info: Loading plugins from folder /data/plugins/ Aug 27 11:27:16 localhost volumio[1145]: info: Loading plugin "system"... Aug 27 11:27:16 localhost volumio[1145]: info: Loading plugin "appearance"... Aug 27 11:27:16 localhost systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Aug 27 11:27:16 localhost systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 11:27:16 localhost systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 11:27:17 localhost upmpdcli[1183]: Could not open config: /tmp/upmpdcli.conf Aug 27 11:27:17 localhost systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 27 11:27:17 localhost systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 27 11:27:18 localhost volumio[1145]: info: Loading plugin "network"... Aug 27 11:27:18 localhost volumio[1145]: info: Refreshing Cached IP Addresses Aug 27 11:27:18 localhost volumio[1145]: info: Loading plugin "services"... Aug 27 11:27:18 localhost volumio[1145]: info: Loading plugin "volumio5onboarding"... Aug 27 11:27:18 localhost sudo[1187]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 11:27:18 localhost sudo[1185]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 11:27:18 localhost sudo[1185]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:18 localhost sudo[1187]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:18 localhost sudo[1185]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:18 localhost sudo[1187]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:18 localhost volumio[1145]: info: Loading plugin "alsa_controller"... Aug 27 11:27:18 localhost sudo[1194]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 27 11:27:18 localhost sudo[1194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:18 localhost sudo[1194]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:18 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 11:27:18 localhost volumio[1145]: info: Loading plugin "wizard"... Aug 27 11:27:18 localhost volumio[1145]: info: Loading plugin "networkfs"... Aug 27 11:27:18 localhost volumio[1145]: info: Starting Udev Watcher for removable devices Aug 27 11:27:18 localhost volumio[1145]: info: Ignoring mount for partition: boot Aug 27 11:27:18 localhost volumio[1145]: info: Ignoring mount for partition: volumio Aug 27 11:27:18 localhost volumio[1145]: info: Ignoring mount for partition: volumio_data Aug 27 11:27:18 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 11:27:18 localhost volumio[1145]: info: Loading plugin "volumio_command_line_client"... Aug 27 11:27:18 localhost volumio[1145]: info: Loading plugin "upnp"... Aug 27 11:27:18 localhost volumio[1145]: info: [1787822838863] Starting Upmpd Daemon Aug 27 11:27:18 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 11:27:18 localhost volumio[1145]: info: Loading plugin "my_music"... Aug 27 11:27:18 localhost volumio[1145]: info: Loading plugin "mpd"... Aug 27 11:27:19 localhost volumio[1145]: info: Loading plugin "upnp_browser"... Aug 27 11:27:21 localhost volumio[1145]: info: Starting UPNP Browser Aug 27 11:27:21 localhost volumio[1145]: info: Loading plugin "bluetooth"... Aug 27 11:27:21 localhost volumio[1145]: info: Applying required configuration parameters for plugin bluetooth Aug 27 11:27:21 localhost volumio[1145]: info: Applying required configuration parameters for plugin bluetooth Aug 27 11:27:21 localhost volumio[1145]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Aug 27 11:27:21 localhost volumio[1145]: info: Loading plugin "alarm-clock"... Aug 27 11:27:22 localhost volumio[1145]: info: Loading plugin "manifestui"... Aug 27 11:27:22 localhost volumio[1145]: info: Loading plugin "metavolumio"... Aug 27 11:27:24 localhost volumio[1145]: info: Loading plugin "airplay_emulation"... Aug 27 11:27:24 localhost volumio[1145]: info: Starting Shairport Sync Aug 27 11:27:24 localhost volumio[1145]: info: Loading plugin "cd_controller"... Aug 27 11:27:24 localhost volumio[1145]: info: Loading plugin "last_100"... Aug 27 11:27:25 localhost volumio[1145]: info: Loading plugin "qobuzconnect"... Aug 27 11:27:25 localhost volumio[1145]: info: Loading plugin "raat"... Aug 27 11:27:25 localhost volumio[1145]: info: RAAT Plugin loaded Aug 27 11:27:25 localhost volumio[1145]: info: Adding restartRAATSocket REST API Endpoint Aug 27 11:27:25 localhost volumio[1145]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Aug 27 11:27:25 localhost volumio[1145]: info: Loading plugin "streaming_services"... Aug 27 11:27:27 localhost volumio[1145]: info: Loading plugin "tidalconnect"... Aug 27 11:27:27 localhost volumio[1145]: info: Loading plugin "webradio"... Aug 27 11:27:27 localhost volumio[1145]: info: Loading plugin "i2s_dacs"... Aug 27 11:27:27 localhost volumio[1145]: info: I2S DAC not set, start Auto-detection Aug 27 11:27:27 localhost volumio[1145]: info: Loading plugin "volumiodiscovery"... Aug 27 11:27:27 localhost volumio[1145]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 11:27:27 localhost volumio[1145]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 11:27:27 localhost volumio[1145]: *** WARNING *** For more information see Aug 27 11:27:27 localhost volumio[1145]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 11:27:27 localhost volumio[1145]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 11:27:27 localhost node[1145]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 11:27:27 localhost volumio[1145]: *** WARNING *** For more information see Aug 27 11:27:27 localhost node[1145]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 11:27:27 localhost node[1145]: *** WARNING *** For more information see Aug 27 11:27:27 localhost node[1145]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 11:27:27 localhost node[1145]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 11:27:27 localhost node[1145]: *** WARNING *** For more information see Aug 27 11:27:27 localhost volumio[1145]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 27 11:27:27 localhost volumio[1145]: info: Discovery: Started advertising with name: StreamPlay Aug 27 11:27:27 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 11:27:27 localhost volumio[1145]: info: Loading plugin "multiroom"... Aug 27 11:27:28 localhost systemd[1]: systemd-fsckd.service: Deactivated successfully. Aug 27 11:27:28 localhost volumio-remote-updater[530]: [2026-08-27 11:27:28] [connect] Successful connection Aug 27 11:27:30 localhost volumio[1145]: info: Applying required configuration parameters for plugin multiroom Aug 27 11:27:30 localhost sudo[1233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Aug 27 11:27:30 localhost sudo[1233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:30 localhost sudo[1233]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:30 localhost volumio[1145]: info: MRS: MultiRoom plugin initialized Aug 27 11:27:30 localhost volumio[1145]: info: MRS: STOPPING SNAPCLIENT Aug 27 11:27:30 localhost volumio[1145]: info: MRS: Snap server stop Aug 27 11:27:30 localhost volumio[1145]: info: MRS: STOPPING volumioStreaming Aug 27 11:27:30 localhost sudo[1252]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Aug 27 11:27:30 localhost sudo[1250]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Aug 27 11:27:30 localhost sudo[1250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:30 localhost volumio[1145]: info: Loading plugin "outputs"... Aug 27 11:27:30 localhost sudo[1252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:30 localhost sudo[1254]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Aug 27 11:27:30 localhost sudo[1254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:30 localhost sudo[1256]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 27 11:27:30 localhost sudo[1256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:30 localhost sudo[1256]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:30 localhost volumio[1145]: info: Loading plugin "albumart"... Aug 27 11:27:30 localhost volumio[1145]: info: Plugin example_plugin is not enabled Aug 27 11:27:30 localhost volumio[1145]: info: Loading plugin "hi_res_audio"... Aug 27 11:27:31 localhost sudo[1250]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:31 localhost sudo[1254]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:31 localhost sudo[1252]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:31 localhost volumio[1261]: Forking 3 albumart workers Aug 27 11:27:32 localhost systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Aug 27 11:27:32 localhost systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 11:27:32 localhost systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 11:27:32 localhost upmpdcli[1301]: Could not open config: /tmp/upmpdcli.conf Aug 27 11:27:32 localhost systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 27 11:27:32 localhost systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 27 11:27:33 localhost volumio[1272]: Starting albumart workers Aug 27 11:27:33 localhost volumio[1271]: Starting albumart workers Aug 27 11:27:33 localhost volumio[1273]: Starting albumart workers Aug 27 11:27:33 localhost volumio[1145]: info: Applying required configuration parameters for plugin hi_res_audio Aug 27 11:27:33 localhost volumio[1145]: info: Loading plugin "inputs"... Aug 27 11:27:34 localhost systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 27 11:27:35 localhost systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 27 11:27:35 localhost volumio[1145]: info: Loading plugin "qobuz"... Aug 27 11:27:38 localhost volumio[1145]: info: Loading plugin "smart_inputs"... Aug 27 11:27:38 localhost volumio[1145]: info: Loading plugin "tidal"... Aug 27 11:27:39 localhost systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 27 11:27:39 localhost systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 27 11:27:39 localhost systemd[1]: setdatetime-helper.service: Consumed 2.170s CPU time. Aug 27 11:27:40 localhost volumio[1145]: info: Loading plugin "updater_comm"... Aug 27 11:27:40 localhost volumio[1145]: info: Plugin mpdemulation is not enabled Aug 27 11:27:40 localhost volumio[1145]: info: Loading plugin "rest_api"... Aug 27 11:27:40 localhost volumio[1145]: info: Loading plugin "websocket"... Aug 27 11:27:40 localhost volumio[1145]: info: Starting Socket.io Server version 1.7.4 Aug 27 11:27:40 localhost volumio[1145]: info: Loading i18n strings for locale en Aug 27 11:27:40 localhost volumio[1145]: Updating browse sources language Aug 27 11:27:40 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 11:27:41 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 11:27:41 localhost volumio[1145]: info: CoreCommandRouter::initPlayerControls Aug 27 11:27:41 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:41 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:41 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:41 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:41 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:41 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:41 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:41 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:41 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 11:27:41 localhost volumio[1145]: Express server listening on port 3000 Aug 27 11:27:41 localhost volumio[1145]: [Metrics] WebUI: 27s 884.55ms Aug 27 11:27:41 localhost volumio[1145]: info: CoreStateMachine::resetVolumioState Aug 27 11:27:41 localhost volumio[1145]: info: CoreStateMachine::getcurrentVolume Aug 27 11:27:41 localhost volumio[1145]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 11:27:41 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:27:41 localhost volumio[1145]: info: Cannot read play queue from file Aug 27 11:27:41 localhost sudo[1378]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 11:27:41 localhost sudo[1378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:41 localhost sudo[1376]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 11:27:41 localhost sudo[1376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:41 localhost volumio[1145]: info: Volumio Network Manager: Network status updated: 1 Aug 27 11:27:41 localhost sudo[1378]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:41 localhost sudo[1376]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:41 localhost volumio[1145]: info: MRS: Removed streaming files Aug 27 11:27:41 localhost volumio[1145]: info: MRS: volumioStreaming STOPPED Aug 27 11:27:41 localhost volumio[1145]: info: MRS: SNAPSERVER STOPPED Aug 27 11:27:41 localhost volumio[1145]: info: MRS: SNAPCLIENT STOPPED Aug 27 11:27:41 localhost volumio[1145]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Aug 27 11:27:41 localhost volumio[1145]: wlan0 Interface doesn't support scanning. Aug 27 11:27:41 localhost volumio[1145]: info: Cannot use regular scanning, forcing with ap-force Aug 27 11:27:41 localhost sudo[1384]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Aug 27 11:27:41 localhost sudo[1384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:43 localhost volumio-remote-updater[530]: [2026-08-27 11:27:43] [connect] Successful connection Aug 27 11:27:44 localhost sudo[1384]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:44 localhost wpa_supplicant[1125]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN Aug 27 11:27:44 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.107 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 27 11:27:44 localhost volumio[1145]: info: CoreStateMachine::pushState Aug 27 11:27:44 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:27:44 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 11:27:44 localhost volumio[1145]: info: CoreCommandRouter::volumioPushState Aug 27 11:27:44 localhost volumio[1145]: info: CoreStateMachine::updateTrackBlock Aug 27 11:27:44 localhost volumio[1145]: info: CorePlayQueue::getTrackBlock Aug 27 11:27:44 localhost volumio[1145]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 11:27:44 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.107 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 27 11:27:44 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 27 11:27:44 localhost volumio[1145]: info: CoreStateMachine::pushState Aug 27 11:27:44 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:27:44 localhost volumio[1145]: info: CoreCommandRouter::volumioPushState Aug 27 11:27:44 localhost volumio[1145]: info: CoreStateMachine::setRepeat null single undefined Aug 27 11:27:44 localhost volumio[1145]: info: CoreStateMachine::pushState Aug 27 11:27:44 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:27:44 localhost volumio[1145]: info: CoreCommandRouter::volumioPushState Aug 27 11:27:44 localhost volumio[1145]: info: CoreStateMachine::setRandom null Aug 27 11:27:44 localhost volumio[1145]: info: CoreStateMachine::pushState Aug 27 11:27:44 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:27:44 localhost volumio[1145]: info: CoreCommandRouter::volumioPushState Aug 27 11:27:44 localhost volumio[1145]: info: Setting Device type: Raspberry PI Aug 27 11:27:44 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 27 11:27:44 localhost sudo[1401]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 27 11:27:44 localhost sudo[1401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:45 localhost volumio[1145]: info: Discovery: adding 1d19fe1e-0fbe-48d9-ae38-2af657b116a6 Aug 27 11:27:45 localhost volumio[1145]: info: Discovery: Found device StreamPlay Aug 27 11:27:45 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:27:45 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:27:45 localhost volumio[1145]: info: MRS: Pushing multiroomSync output for this device Aug 27 11:27:45 localhost volumio[1145]: info: MRS: Pushing multiroomSync output Aug 27 11:27:45 localhost volumio[1145]: info: Adding audio output: Aug 27 11:27:45 localhost volumio[1145]: info: Adding audio output: Aug 27 11:27:45 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.109 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 27 11:27:45 localhost volumio[1145]: info: Discovery: this is already registered, 1d19fe1e-0fbe-48d9-ae38-2af657b116a6 Aug 27 11:27:45 localhost volumio[1145]: info: Discovery: Found device StreamPlay Aug 27 11:27:45 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:27:45 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:27:45 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.109 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 27 11:27:45 localhost volumio[1145]: info: Completed loading Core Plugins Aug 27 11:27:45 localhost volumio[1145]: info: Preparing to generate the ALSA configuration file Aug 27 11:27:45 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.105 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 27 11:27:45 localhost volumio[1145]: info: Discovery: adding 88e8287b-2d0d-45ce-a84a-d008a5b492e3 Aug 27 11:27:45 localhost volumio[1145]: info: Discovery: Found device BassFly Aug 27 11:27:45 localhost volumio[1145]: info: Discovery: Connecting to remote: 192.168.1.101 Aug 27 11:27:45 localhost volumio[1145]: info: Discovery: adding 1eee4f22-7aa6-4f68-b6dd-bc45755add4e Aug 27 11:27:45 localhost volumio[1145]: info: Discovery: Found device Integro-Livingroom Aug 27 11:27:45 localhost volumio[1145]: info: Discovery: Connecting to remote: 192.168.1.109 Aug 27 11:27:45 localhost volumio[1145]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 27 11:27:45 localhost volumio[1145]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 27 11:27:45 localhost volumio[1145]: info: Reading ALSA contributions from plugins. Aug 27 11:27:45 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.105 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 27 11:27:45 localhost volumio[1145]: info: Discovery: adding f7b31a80-f672-4a24-a198-e040ba8f24bc Aug 27 11:27:45 localhost volumio[1145]: info: Discovery: Found device Motivo-dev0 Aug 27 11:27:45 localhost volumio[1145]: info: Discovery: Connecting to remote: 192.168.1.107 Aug 27 11:27:45 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.107 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 27 11:27:45 localhost volumio[1145]: info: Discovery: adding 52212d80-c6b4-4232-b137-2992f7159502 Aug 27 11:27:45 localhost volumio[1145]: info: Discovery: Found device Integro-Studio Aug 27 11:27:45 localhost volumio[1145]: info: Discovery: Connecting to remote: 192.168.1.105 Aug 27 11:27:45 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.107 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 27 11:27:45 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Aug 27 11:27:45 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Aug 27 11:27:45 localhost volumio[1145]: info: MRS: Pushing audio output on request Aug 27 11:27:45 localhost volumio[1145]: info: MRS: Pushing multiroomSync output Aug 27 11:27:45 localhost volumio[1145]: info: Discovery: Connected to remote: 192.168.1.109 Aug 27 11:27:45 localhost volumio[1145]: info: MRS: Pushing audio output on request Aug 27 11:27:45 localhost volumio[1145]: info: MRS: Pushing multiroomSync output Aug 27 11:27:45 localhost volumio[1145]: info: Discovery: Connected to remote: 192.168.1.107 Aug 27 11:27:45 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.109 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Aug 27 11:27:45 localhost volumio[1145]: info: Asound.conf file unchanged, so no further update is needed Aug 27 11:27:45 localhost volumio[1145]: info: Output device has changed, restarting MPD Aug 27 11:27:45 localhost volumio[1145]: info: Output device has changed, restarting Shairport Sync Aug 27 11:27:45 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:45 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:27:45 localhost sudo[1407]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 11:27:45 localhost sudo[1407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:45 localhost sudo[1409]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 11:27:45 localhost sudo[1409]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:45 localhost sudo[1407]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:45 localhost volumio[1145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 11:27:45 localhost volumio[1145]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 11:27:45 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:45 localhost volumio[1145]: info: ___________ START PLUGINS ___________ Aug 27 11:27:45 localhost volumio[1145]: info: ControllerMpd::onStart: Initializing MPD Aug 27 11:27:45 localhost volumio[1145]: info: Creating MPD Configuration file Aug 27 11:27:45 localhost systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 11:27:45 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 11:27:45 localhost volumio[1145]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 11:27:45 localhost volumio[1145]: info: [1787822865778] CoreMusicLibrary::Adding element Media Servers Aug 27 11:27:45 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 11:27:45 localhost sudo[1420]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 27 11:27:45 localhost systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 11:27:45 localhost sudo[1420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:45 localhost sudo[1424]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 11:27:45 localhost sudo[1422]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 11:27:45 localhost sudo[1424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:45 localhost sudo[1422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:45 localhost sudo[1422]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:45 localhost volumio[1145]: info: UPNP Browser: Client initialized successfully Aug 27 11:27:45 localhost volumio[1145]: ------------------------------------ BT MESSAGE: [FUNC] onStart Aug 27 11:27:45 localhost volumio[1145]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Aug 27 11:27:45 localhost volumio[1145]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Aug 27 11:27:45 localhost volumio[1145]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Aug 27 11:27:45 localhost volumio[1145]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Aug 27 11:27:45 localhost volumio[1145]: info: Adding METAVOLUMIO REST API Endpoints Aug 27 11:27:45 localhost volumio[1145]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Aug 27 11:27:45 localhost volumio[1145]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Aug 27 11:27:45 localhost volumio[1145]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Aug 27 11:27:45 localhost volumio[1145]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Aug 27 11:27:45 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:45 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:27:45 localhost systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 11:27:45 localhost systemd[1]: mpd.service: Deactivated successfully. Aug 27 11:27:45 localhost systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 11:27:45 localhost volumio[1145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 11:27:45 localhost systemd[1]: mpd.socket: Deactivated successfully. Aug 27 11:27:45 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:45 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:27:45 localhost systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 11:27:45 localhost systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 11:27:46 localhost sudo[1420]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:46 localhost systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 11:27:46 localhost systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 11:27:46 localhost volumio[1145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 11:27:46 localhost volumio[1145]: info: Preparing CD Folders Aug 27 11:27:46 localhost volumio[1145]: info: Adding CD REST API Endpoints Aug 27 11:27:46 localhost volumio[1145]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Aug 27 11:27:46 localhost volumio[1145]: info: Starting UDEV Watcher for CD Aug 27 11:27:46 localhost volumio[1145]: info: Detecting CD presence with UDEV Aug 27 11:27:46 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Aug 27 11:27:46 localhost bluetoothd[811]: Path / reserved for Adv Monitor app :1.19 Aug 27 11:27:46 localhost bluetoothd[811]: Adv Monitor app :1.19 disconnected from D-Bus Aug 27 11:27:46 localhost sudo[1441]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 11:27:46 localhost sudo[1441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 11:27:46 localhost sudo[1451]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 27 11:27:46 localhost sudo[1441]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:46 localhost volumio5-onboarding[1431]: time=2026-08-27T11:27:46.706+02:00 level=INFO msg="running volumio4-device-gateway" version=2a6bbeeb+CHANGES buildDate=2026-07-03T07:51:28Z Aug 27 11:27:47 localhost systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Aug 27 11:27:47 localhost systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 11:27:47 localhost systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 11:27:48 localhost sudo[1401]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:51 localhost volumio[1145]: warn: [cd-plugin] cdspeedctl: device or media not ready Aug 27 11:27:51 localhost volumio[1145]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 11:27:51 localhost volumio[1145]: info: [1787822871284] CoreMusicLibrary::Adding element Last_100 Aug 27 11:27:51 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 11:27:51 localhost volumio[1145]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Aug 27 11:27:51 localhost volumio[1145]: info: QobuzConnect: Starting Qobuz Connect socket and service Aug 27 11:27:51 localhost volumio[1145]: info: Starting RAAT Plugin Aug 27 11:27:51 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Aug 27 11:27:51 localhost volumio[1145]: info: Additional UI Settings Added for plugin music_service/raat Aug 27 11:27:51 localhost volumio[1145]: info: Registering DSP Elements listener and retrieving current ones Aug 27 11:27:51 localhost volumio[1145]: info: Additional DSP elements updated Aug 27 11:27:51 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:51 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:51 localhost volumio[1145]: info: Updating RAAT Signal Path Aug 27 11:27:51 localhost volumio[1145]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Aug 27 11:27:51 localhost volumio[1145]: info: Streaming services startup Aug 27 11:27:51 localhost volumio[1145]: info: Starting Streaming Daemon Aug 27 11:27:51 localhost sudo[1485]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 27 11:27:51 localhost sudo[1485]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:51 localhost sudo[1489]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 27 11:27:51 localhost sudo[1489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:51 localhost sudo[1497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 11:27:51 localhost sudo[1497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:51 localhost volumio[1145]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 11:27:51 localhost volumio[1145]: info: [1787822871584] CoreMusicLibrary::Adding element Webradio Aug 27 11:27:51 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 11:27:51 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 11:27:51 localhost volumio[1145]: info: Initializing BBC Radios Aug 27 11:27:51 localhost sudo[1485]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:51 localhost sudo[1489]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:51 localhost sudo[1502]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 27 11:27:51 localhost sudo[1502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:52 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 11:27:52 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:27:52 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Aug 27 11:27:52 localhost sudo[1497]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:52 localhost volumio[1145]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 11:27:53 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:53 localhost systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 27 11:27:53 localhost sudo[1502]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:53 localhost volumio[1145]: error: Hi Res Audio Failed Login: Missing Login Data Aug 27 11:27:53 localhost volumio[1145]: info: Adding HIGHRESAUDIO REST API Endpoints Aug 27 11:27:53 localhost volumio[1145]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 27 11:27:53 localhost volumio[1145]: info: Initializing Serial Communication on port /dev/ttyS0 Aug 27 11:27:53 localhost volumio[1145]: info: Touch Event Listener Process Starting Aug 27 11:27:53 localhost volumio[1145]: info: Adding inputs REST Endpoints Aug 27 11:27:53 localhost volumio[1145]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Aug 27 11:27:53 localhost volumio[1145]: info: Scanning Audio Inputs Aug 27 11:27:53 localhost volumio[1145]: info: Checking against Known Cards name Aug 27 11:27:53 localhost volumio[1145]: info: Adding Server instance for streaming Aug 27 11:27:53 localhost volumio[1145]: info: Volumio Calling Home Aug 27 11:27:53 localhost volumio[1145]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Aug 27 11:27:53 localhost volumio[1145]: (node:1145) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Aug 27 11:27:53 localhost volumio[1145]: (Use `node --trace-deprecation ...` to show where the warning was created) Aug 27 11:27:53 localhost volumio[1145]: info: Stopping AccessToken refresher cron for QOBUZ Aug 27 11:27:54 localhost volumio[1145]: info: AccessToken refresher cron started for QOBUZ Aug 27 11:27:54 localhost volumio[1145]: info: Adding TIDAL REST API Endpoints Aug 27 11:27:54 localhost volumio[1145]: info: Adding QOBUZ REST API Endpoints Aug 27 11:27:55 localhost volumio[1145]: info: Serial port opened successfully Aug 27 11:27:55 localhost volumio[1145]: info: Sending serial start messages Aug 27 11:27:55 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:55 localhost volumio[1145]: info: Reporting MCU Network Status: 1 Aug 27 11:27:55 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.109 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Aug 27 11:27:55 localhost volumio[1145]: info: MRS: Pushing audio output on request Aug 27 11:27:55 localhost volumio[1145]: info: MRS: Pushing multiroomSync output Aug 27 11:27:55 localhost volumio[1145]: info: Discovery: Connected to remote: 192.168.1.105 Aug 27 11:27:55 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.105 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Aug 27 11:27:55 localhost volumio[1145]: info: Touch Event Listener Process Closed Aug 27 11:27:55 localhost volumio[1145]: error: Cannot start Volumio Streaming Daemon Aug 27 11:27:55 localhost volumio[1145]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 27 11:27:55 localhost volumio[1145]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 27 11:27:55 localhost volumio[1145]: info: RAAT Albumart path created successfully Aug 27 11:27:55 localhost volumio[1145]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Aug 27 11:27:55 localhost volumio[1145]: info: MPD Permissions set Aug 27 11:27:55 localhost volumio[1145]: info: MPD Permissions set Aug 27 11:27:55 localhost volumio[1145]: info: Upmpdcli Daemon Started Aug 27 11:27:55 localhost sudo[1530]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Aug 27 11:27:55 localhost sudo[1530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:55 localhost systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Aug 27 11:27:55 localhost sudo[1530]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:55 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.105 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Aug 27 11:27:55 localhost volumiobt[1536]: INFO [BTSTART] Ensuring Bluetooth directory exists... Aug 27 11:27:55 localhost volumio[1145]: info: MRS: Pushing audio output on request Aug 27 11:27:55 localhost volumio[1145]: info: MRS: Pushing multiroomSync output Aug 27 11:27:55 localhost volumio[1145]: info: Discovery: Connected to remote: 192.168.1.101 Aug 27 11:27:55 localhost sudo[1537]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Aug 27 11:27:55 localhost sudo[1537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:55 localhost volumio[1145]: info: Volumio called home Aug 27 11:27:55 localhost sudo[1537]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:55 localhost sudo[1539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Aug 27 11:27:55 localhost sudo[1539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:55 localhost sudo[1539]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:55 localhost volumiobt[1542]: INFO [BTSTART] Powering on Bluetooth if needed... Aug 27 11:27:56 localhost bluetoothd[811]: Adv Monitor app :1.22 disconnected from D-Bus Aug 27 11:27:56 localhost volumiobt[1545]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Aug 27 11:27:56 localhost volumio[1145]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Aug 27 11:27:56 localhost volumio[1145]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Aug 27 11:27:56 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:56 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:56 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:56 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:56 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:56 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:56 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:56 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:56 localhost volumiobt[1546]: [176B blob data] Aug 27 11:27:56 localhost volumiobt[1546]: [157B blob data] Aug 27 11:27:56 localhost volumiobt[1546]: [157B blob data] Aug 27 11:27:56 localhost volumiobt[1546]: [157B blob data] Aug 27 11:27:56 localhost volumiobt[1546]: [bluetoothctl]> discoverable on Aug 27 11:27:56 localhost volumiobt[1546]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Aug 27 11:27:56 localhost volumiobt[1546]: [bluetoothctl]> pairable on Aug 27 11:27:56 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:27:56 localhost volumiobt[1546]: [bluetoothctl]> Aug 27 11:27:56 localhost volumiobt[1552]: INFO [BTSTART] Registering Bluetooth agent... Aug 27 11:27:56 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:56 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:56 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:56 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:56 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:56 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:56 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:56 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:56 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:56 localhost bluetoothd[811]: Adv Monitor app :1.23 disconnected from D-Bus Aug 27 11:27:56 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:27:56 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:27:56 localhost volumiobt[1553]: [NEW] Media /org/bluez/hci0 Aug 27 11:27:56 localhost volumiobt[1553]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Aug 27 11:27:56 localhost volumiobt[1553]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Aug 27 11:27:56 localhost volumiobt[1553]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Aug 27 11:27:56 localhost bluetoothd[811]: Adv Monitor app :1.24 disconnected from D-Bus Aug 27 11:27:56 localhost volumiobt[1559]: No agent is registered Aug 27 11:27:56 localhost volumiobt[1559]: [NEW] Media /org/bluez/hci0 Aug 27 11:27:56 localhost volumiobt[1559]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Aug 27 11:27:56 localhost volumiobt[1559]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Aug 27 11:27:56 localhost volumiobt[1559]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Aug 27 11:27:56 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.109 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Aug 27 11:27:56 localhost bluetoothd[811]: Adv Monitor app :1.25 disconnected from D-Bus Aug 27 11:27:56 localhost volumio[1145]: info: TidalConnect service stoped! Aug 27 11:27:56 localhost volumiobt[1562]: INFO [BTSTART] Agent registered successfully. Aug 27 11:27:56 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.105 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Aug 27 11:27:56 localhost volumiobt[1569]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Aug 27 11:27:56 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Aug 27 11:27:56 localhost volumio[1145]: info: Starting Shairport Sync Aug 27 11:27:56 localhost volumio[1145]: info: Starting Shairport Sync Aug 27 11:27:56 localhost volumio5-onboarding[1431]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:50270->127.0.0.1:3000: i/o timeout Aug 27 11:27:56 localhost volumio[1145]: info: Starting Shairport Sync Aug 27 11:27:56 localhost systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 27 11:27:56 localhost systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 27 11:27:56 localhost volumio[1145]: info: Discovery: Disconnected from remote: 192.168.1.105 Aug 27 11:27:56 localhost sudo[1576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 11:27:56 localhost sudo[1576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:56 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 Aug 27 11:27:56 localhost volumio[1145]: info: MRS: Found cast device: TIMVISIONBOX-f7a36d81e4630db45356f6ffd7c5cb06 Aug 27 11:27:56 localhost volumio[1145]: info: Adding audio output: Aug 27 11:27:56 localhost sudo[1578]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 11:27:56 localhost sudo[1578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:56 localhost sudo[1580]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 11:27:56 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.107 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 21 Aug 27 11:27:56 localhost sudo[1580]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:56 localhost volumio[1145]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 27 11:27:56 localhost volumio[1145]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 27 11:27:56 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.107 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 22 Aug 27 11:27:56 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 23 Aug 27 11:27:56 localhost systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 11:27:56 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.109 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 24 Aug 27 11:27:56 localhost systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Aug 27 11:27:56 localhost sudo[1585]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 27 11:27:56 localhost sudo[1585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:27:56 localhost systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 11:27:56 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.109 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Aug 27 11:27:56 localhost systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 11:27:56 localhost systemd[1]: shairport-sync.service: Consumed 1.855s CPU time. Aug 27 11:27:56 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.105 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Aug 27 11:27:56 localhost systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 11:27:56 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 27 Aug 27 11:27:57 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.105 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 28 Aug 27 11:27:57 localhost systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 11:27:57 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.109 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 29 Aug 27 11:27:57 localhost sudo[1578]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:57 localhost systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 11:27:57 localhost sudo[1576]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:57 localhost sudo[1580]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:57 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 30 Aug 27 11:27:57 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.105 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 31 Aug 27 11:27:57 localhost volumio[1145]: info: Shairport-Sync Started Aug 27 11:27:57 localhost volumio[1145]: Error adding Membership: Error: addMembership EINVAL Aug 27 11:27:57 localhost volumio[1145]: info: Shairport-Sync Started Aug 27 11:27:57 localhost volumio[1145]: info: Shairport-Sync Started Aug 27 11:27:57 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.107 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 32 Aug 27 11:27:57 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.107 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 33 Aug 27 11:27:57 localhost volumio5-onboarding[1588]: time=2026-08-27T11:27:57.146+02:00 level=INFO msg="running volumio4-device-gateway" version=2a6bbeeb+CHANGES buildDate=2026-07-03T07:51:28Z Aug 27 11:27:57 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 34 Aug 27 11:27:57 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.109 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 35 Aug 27 11:27:57 localhost systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 27 11:27:57 localhost sudo[1585]: pam_unix(sudo:session): session closed for user root Aug 27 11:27:57 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.105 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 36 Aug 27 11:27:57 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.109 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 37 Aug 27 11:27:57 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 38 Aug 27 11:27:57 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.105 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 39 Aug 27 11:27:58 localhost volumio[1145]: info: Executing endpoint qc_getconfig Aug 27 11:27:58 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 27 11:27:58 localhost volumio[1145]: info: MRS: Getting audio outputs on start Aug 27 11:27:58 localhost volumio[1145]: info: MRS: Requesting all other devices output Aug 27 11:27:58 localhost qobuz-connect[1518]: 20260827 11:27:58.174 [1518.1518] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 27 11:27:58 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.107 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 40 Aug 27 11:27:58 localhost volumio[1145]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Aug 27 11:27:58 localhost volumio[1145]: info: QobuzConnect: QOBUZ Connect daemon connected Aug 27 11:27:58 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.107 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 41 Aug 27 11:27:58 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 42 Aug 27 11:27:58 localhost volumio[1145]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 43 Aug 27 11:27:58 localhost volumio[1145]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 44 Aug 27 11:27:58 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.109 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 45 Aug 27 11:27:58 localhost volumio[1145]: info: MRS: Pushing audio output on request Aug 27 11:27:58 localhost volumio[1145]: info: MRS: Pushing multiroomSync output Aug 27 11:27:58 localhost volumio[1145]: info: Discovery: Connected to remote: 192.168.1.105 Aug 27 11:27:58 localhost qobuz-connect[1518]: 20260827 11:27:58.536 [1518.1518] INFO VolumeManager: [0x2a6e148]: Setting new playback volume: 75 Aug 27 11:27:58 localhost qobuz-connect[1518]: 20260827 11:27:58.536 [1518.1518] INFO VolumeManager: [0x2a6e148]: Setting new mute state: 0 Aug 27 11:27:58 localhost qobuz-connect[1518]: 20260827 11:27:58.536 [1518.1518] INFO AudioStreamManager: [0x2a6dea0]: Setting new audio download buffer size: 1048576 Aug 27 11:27:58 localhost qobuz-connect[1518]: 20260827 11:27:58.536 [1518.1518] INFO QobuzConnect: [0x2a6ea10]: Client initialized! Aug 27 11:27:58 localhost qobuz-connect[1518]: 20260827 11:27:58.536 [1518.1518] INFO SampleApp: Starting Avahi advertising, name: StreamPlay, service name: _qobuz-connect._tcp Aug 27 11:27:58 localhost volumio[1145]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 46 Aug 27 11:27:58 localhost qobuz-connect[1518]: 20260827 11:27:58.587 [1518.1518] INFO LocalConfigManager: [0x2a6dbc8]: Starting Local Configuration server Aug 27 11:27:58 localhost qobuz-connect[1518]: 20260827 11:27:58.587 [1518.1518] INFO SampleApp: Starting Local configuration server Aug 27 11:27:58 localhost qobuz-connect[1518]: 20260827 11:27:58.588 [1518.1518] INFO SampleApp: Connected to UNIX socket client 0x2a58908 Aug 27 11:27:58 localhost qobuz-connect[1518]: 20260827 11:27:58.588 [1518.1518] INFO SampleApp: Playback volume changed: 75 Aug 27 11:27:58 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.105 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 47 Aug 27 11:27:58 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:27:58 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:27:58 localhost volumio-remote-updater[530]: [2026-08-27 11:27:58] [connect] Successful connection Aug 27 11:27:58 localhost volumio[1145]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 47 Aug 27 11:27:58 localhost volumio[1145]: info: MRS: Adding multiroomSync output Aug 27 11:27:58 localhost volumio[1145]: info: Adding audio output: Aug 27 11:27:58 localhost volumio[1145]: info: MRS: Pushing multiroomSync output Aug 27 11:27:58 localhost volumio[1145]: info: Received Get System Info Aug 27 11:27:58 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 11:27:58 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 11:27:58 localhost volumio[1145]: info: Discovery: Getting this device information Aug 27 11:27:58 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:27:58 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:27:58 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 11:28:00 localhost volumio5-onboarding[1588]: time=2026-08-27T11:28:00.176+02:00 level=INFO msg="system info for 80201f7d194ea5975a6f060909816c95" deviceName=StreamPlay deviceVariant=spc0vara deviceModel=StreamPlay-C0 softwareVersion=4.187 Aug 27 11:28:00 localhost volumio5-onboarding[1588]: time=2026-08-27T11:28:00.268+02:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 27 11:28:00 localhost volumio[1145]: info: Received Get System Info Aug 27 11:28:00 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 11:28:00 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 11:28:00 localhost volumio[1145]: info: Discovery: Getting this device information Aug 27 11:28:00 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:00 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:00 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 11:28:00 localhost volumio[1145]: info: TidalConnect service started! Aug 27 11:28:00 localhost volumio[1145]: info: MRS: Adding multiroomSync output Aug 27 11:28:00 localhost volumio[1145]: info: Adding audio output: Aug 27 11:28:00 localhost volumio[1145]: info: MRS: Pushing multiroomSync output Aug 27 11:28:00 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Aug 27 11:28:00 localhost volumio[1145]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 48 Aug 27 11:28:00 localhost volumio[1145]: info: MRS: Adding multiroomSync output Aug 27 11:28:00 localhost volumio[1145]: info: Adding audio output: Aug 27 11:28:00 localhost volumio[1145]: info: MRS: Pushing multiroomSync output Aug 27 11:28:00 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Aug 27 11:28:00 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:00 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:00 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Aug 27 11:28:00 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:00 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:00 localhost volumio-remote-updater[530]: [2026-08-27 11:28:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1787822878 101 Aug 27 11:28:00 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:00 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:00 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:00 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:00 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Aug 27 11:28:00 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Aug 27 11:28:00 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:00 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:00 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.109 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 49 Aug 27 11:28:00 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:00 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:00 localhost volumio[1145]: info: Executing endpoint tc_getconfig Aug 27 11:28:00 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 27 11:28:00 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:00 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:00 localhost vtcs[1597]: STARTING TidalConnect services, version: 1.6.1 Aug 27 11:28:01 localhost vtcs[1597]: STARTED TidalConnect services. Aug 27 11:28:01 localhost sudo[1638]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 11:28:01 localhost sudo[1636]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 11:28:01 localhost sudo[1638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:01 localhost sudo[1636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:01 localhost sudo[1638]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:01 localhost sudo[1636]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:01 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.109 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 50 Aug 27 11:28:01 localhost volumio[1145]: info: Executing endpoint tc_connect Aug 27 11:28:01 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Aug 27 11:28:01 localhost volumio[1145]: info: Connecting to TidalConnect Aug 27 11:28:01 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 51 Aug 27 11:28:01 localhost volumio[1145]: info: CoreCommandRouter::servicePushState Aug 27 11:28:01 localhost volumio[1145]: info: CoreStateMachine::pushState Aug 27 11:28:01 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:01 localhost volumio[1145]: info: CoreCommandRouter::volumioPushState Aug 27 11:28:01 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:01 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:01 localhost volumio[1145]: info: MRS: Pushing multiroomSync output update for this device Aug 27 11:28:01 localhost volumio[1145]: info: MRS: Pushing multiroomSync output Aug 27 11:28:01 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:01 localhost volumio[1145]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Aug 27 11:28:01 localhost volumio[1145]: verbose: CURRENT POSITION 0 Aug 27 11:28:01 localhost volumio[1145]: info: CoreStateMachine::syncState stateService stop Aug 27 11:28:01 localhost volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Aug 27 11:28:01 localhost volumio[1145]: info: CoreStateMachine::pushState Aug 27 11:28:01 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:01 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 11:28:01 localhost volumio[1145]: info: CoreCommandRouter::volumioPushState Aug 27 11:28:01 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:01 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:01 localhost volumio[1145]: info: MRS: Pushing multiroomSync output update for this device Aug 27 11:28:01 localhost volumio[1145]: info: MRS: Pushing multiroomSync output Aug 27 11:28:01 localhost volumio[1145]: info: No code Aug 27 11:28:01 localhost volumio[1145]: info: CoreStateMachine::pushState Aug 27 11:28:01 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:01 localhost volumio[1145]: info: CoreCommandRouter::volumioPushState Aug 27 11:28:01 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:01 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:01 localhost volumio[1145]: info: MRS: Pushing multiroomSync output update for this device Aug 27 11:28:01 localhost volumio[1145]: info: MRS: Pushing multiroomSync output Aug 27 11:28:01 localhost volumio[1145]: info: CoreCommandRouter::servicePushState Aug 27 11:28:01 localhost volumio[1145]: info: CoreStateMachine::pushState Aug 27 11:28:01 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:01 localhost volumio[1145]: info: CoreCommandRouter::volumioPushState Aug 27 11:28:01 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:01 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:01 localhost volumio[1145]: info: MRS: Pushing multiroomSync output update for this device Aug 27 11:28:01 localhost volumio[1145]: info: MRS: Pushing multiroomSync output Aug 27 11:28:01 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:01 localhost volumio[1145]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Aug 27 11:28:01 localhost volumio[1145]: verbose: CURRENT POSITION 0 Aug 27 11:28:01 localhost volumio[1145]: info: CoreStateMachine::syncState stateService stop Aug 27 11:28:01 localhost volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Aug 27 11:28:01 localhost volumio[1145]: info: CoreStateMachine::pushState Aug 27 11:28:01 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:01 localhost volumio[1145]: info: CoreCommandRouter::volumioPushState Aug 27 11:28:01 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:01 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:01 localhost volumio[1145]: info: MRS: Pushing multiroomSync output update for this device Aug 27 11:28:01 localhost volumio[1145]: info: MRS: Pushing multiroomSync output Aug 27 11:28:01 localhost volumio[1145]: info: No code Aug 27 11:28:01 localhost volumio[1145]: info: CoreStateMachine::pushState Aug 27 11:28:01 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:01 localhost volumio[1145]: info: CoreCommandRouter::volumioPushState Aug 27 11:28:01 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:01 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:01 localhost volumio[1145]: info: MRS: Pushing multiroomSync output update for this device Aug 27 11:28:01 localhost volumio[1145]: info: MRS: Pushing multiroomSync output Aug 27 11:28:02 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126 from 192.168.1.113 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 52 Aug 27 11:28:02 localhost volumiobt[1570]: 2026-08-27 11:28:02 a2dp-agent [INFO] Connecting to system D-Bus Aug 27 11:28:02 localhost volumiobt[1570]: 2026-08-27 11:28:02 a2dp-agent [INFO] Connected to system D-Bus Aug 27 11:28:02 localhost volumiobt[1570]: 2026-08-27 11:28:02 bluezutils [INFO] Found adapter at: /org/bluez/hci0 Aug 27 11:28:02 localhost volumiobt[1570]: 2026-08-27 11:28:02 a2dp-agent [INFO] Found Bluetooth adapter: /org/bluez/hci0 Aug 27 11:28:02 localhost volumiobt[1570]: 2026-08-27 11:28:02 a2dp-agent [INFO] Set DiscoverableTimeout to infinite Aug 27 11:28:02 localhost volumiobt[1570]: 2026-08-27 11:28:02 a2dp-agent [INFO] Enabled Discoverable mode Aug 27 11:28:02 localhost volumiobt[1570]: 2026-08-27 11:28:02 a2dp-agent [INFO] Agent registered at /local/a2dpagent Aug 27 11:28:02 localhost volumiobt[1570]: 2026-08-27 11:28:02 a2dp-agent [INFO] Agent set as default Aug 27 11:28:02 localhost volumiobt[1570]: 2026-08-27 11:28:02 a2dp-agent [INFO] A2DP agent running, waiting for connections... Aug 27 11:28:02 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126 from 192.168.1.113 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 53 Aug 27 11:28:02 localhost volumio[1145]: verbose: New Socket.io Connection to 192.168.1.126:3000 from 192.168.1.105 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 54 Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:03 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:03 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:03 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Aug 27 11:28:03 localhost volumio[1145]: info: Received Get System Info Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 11:28:03 localhost volumio[1145]: info: Discovery: Getting this device information Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:03 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 27 11:28:03 localhost volumio-remote-updater[530]: Test mode disabled Aug 27 11:28:03 localhost volumio-remote-updater[530]: Alpha mode disabled Aug 27 11:28:03 localhost volumio-remote-updater[530]: Alpha legacy test mode disabled Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 11:28:03 localhost volumio[1145]: info: Retrieving Cloud Streaming UI Aug 27 11:28:03 localhost volumio[1145]: info: Getting Tidal Cloud Configuration Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 11:28:03 localhost volumio[1145]: info: Getting Qobuz Cloud Configuration Aug 27 11:28:03 localhost volumio[1145]: info: Asking plugin for UI Config Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 11:28:03 localhost volumio[1145]: info: Getting Spotify Cloud Configuration Aug 27 11:28:03 localhost volumio[1145]: info: Asking plugin for UI Config Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 11:28:03 localhost volumio[1145]: info: Saving Spotify Acccount Aug 27 11:28:03 localhost volumio[1145]: info: Got it Aug 27 11:28:03 localhost volumio[1145]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 27 11:28:03 localhost mpd[1452]: 2026-08-27T11:28:03 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 11:28:03 localhost volumio[1145]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 11:28:03 localhost volumio[1145]: info: Got Tidal Cloud Configuration Aug 27 11:28:03 localhost volumio[1145]: info: Got it Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 11:28:03 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 27 11:28:04 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 11:28:04 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 11:28:04 localhost systemd[1]: Started mpd.service - Music Player Daemon. Aug 27 11:28:04 localhost volumio[1145]: info: Received Get System Info Aug 27 11:28:04 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 11:28:04 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 11:28:04 localhost volumio[1145]: info: Discovery: Getting this device information Aug 27 11:28:04 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:04 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:04 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 11:28:04 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 11:28:04 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 11:28:04 localhost volumio5-onboarding[1588]: time=2026-08-27T11:28:04.412+02:00 level=INFO msg="enabling local network discovery" Aug 27 11:28:04 localhost sudo[1409]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:04 localhost sudo[1424]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:04 localhost volumio[1145]: info: Completed starting Core Plugins Aug 27 11:28:04 localhost volumio[1145]: info: ------------------------------------------- Aug 27 11:28:04 localhost volumio[1145]: info: ----- MyVolumio plugins startup ---- Aug 27 11:28:04 localhost volumio[1145]: info: ------------------------------------------- Aug 27 11:28:04 localhost volumio[1145]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 27 11:28:04 localhost volumio5-onboarding[1588]: time=2026-08-27T11:28:04.685+02:00 level=INFO msg="enabling BLE discovery" Aug 27 11:28:05 localhost volumio[1145]: error: MPD error: The expression evaluated to a falsy value: Aug 27 11:28:05 localhost volumio[1145]: assert.ok(self.idling) Aug 27 11:28:05 localhost volumio[1145]: error: The expression evaluated to a falsy value: Aug 27 11:28:05 localhost volumio[1145]: assert.ok(self.idling) Aug 27 11:28:05 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Aug 27 11:28:05 localhost volumio[1145]: info: Loading i18n strings for locale en Aug 27 11:28:05 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Aug 27 11:28:05 localhost volumio[1145]: info: Setting timezone to Europe/Rome Aug 27 11:28:05 localhost sudo[1667]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Aug 27 11:28:05 localhost sudo[1667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:05 localhost sudo[1667]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:05 localhost sudo[1671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Rome /etc/localtime Aug 27 11:28:05 localhost sudo[1671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:05 localhost sudo[1671]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:05 localhost sudo[1675]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Aug 27 11:28:05 localhost sudo[1675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:05 localhost sudo[1675]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:05 localhost volumio5-onboarding[1588]: time=2026-08-27T11:28:05.673+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 27 11:28:05 localhost sudo[1679]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Rome Aug 27 11:28:05 localhost sudo[1679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:06 localhost dbus-daemon[507]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.33' (uid=0 pid=1680 comm="/usr/bin/timedatectl set-timezone Europe/Rome") Aug 27 11:28:06 localhost systemd[1]: Starting systemd-timedated.service - Time & Date Service... Aug 27 11:28:06 localhost dbus-daemon[507]: [system] Successfully activated service 'org.freedesktop.timedate1' Aug 27 11:28:06 localhost systemd[1]: Started systemd-timedated.service - Time & Date Service. Aug 27 11:28:06 localhost sudo[1679]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:06 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 11:28:06 localhost volumio[1145]: info: MPD running with PID1452 Aug 27 11:28:06 localhost volumio[1145]: ,establishing connection Aug 27 11:28:06 localhost volumio[1145]: info: Fetching Streaming Services browse cache Aug 27 11:28:06 localhost volumio[1145]: error: updateQueue error: null Aug 27 11:28:06 localhost volumio[1145]: error: updateQueue error: null Aug 27 11:28:07 localhost volumio[1145]: Updating browse sources language Aug 27 11:28:07 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 11:28:08 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 27 11:28:09 localhost volumio[1145]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 27 11:28:10 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Aug 27 11:28:10 localhost volumio[1145]: info: Loading i18n strings for locale en Aug 27 11:28:10 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Aug 27 11:28:10 localhost volumio[1145]: info: Setting timezone to Europe/Rome Aug 27 11:28:10 localhost sudo[1704]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Aug 27 11:28:10 localhost sudo[1704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:10 localhost sudo[1704]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:10 localhost sudo[1709]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Rome /etc/localtime Aug 27 11:28:10 localhost sudo[1709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:10 localhost sudo[1709]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:10 localhost sudo[1713]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Aug 27 11:28:10 localhost sudo[1713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:10 localhost sudo[1713]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:10 localhost sudo[1717]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Rome Aug 27 11:28:10 localhost sudo[1717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:10 localhost sudo[1717]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:10 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 27 11:28:10 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 27 11:28:10 localhost volumio[1145]: info: Fetching Streaming Services browse cache Aug 27 11:28:11 localhost sudo[1720]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 27 11:28:11 localhost sudo[1720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:11 localhost volumio[1145]: Updating browse sources language Aug 27 11:28:11 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 11:28:11 localhost sudo[1720]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:11 localhost volumio[1145]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Aug 27 11:28:11 localhost volumio[1145]: wlan0 Interface doesn't support scanning. Aug 27 11:28:11 localhost volumio[1145]: info: Cannot use regular scanning, forcing with ap-force Aug 27 11:28:11 localhost sudo[1726]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Aug 27 11:28:11 localhost sudo[1726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:14 localhost sudo[1726]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 27 11:28:14 localhost volumio[1145]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 27 11:28:16 localhost upmpdcli[1728]: writing RSA key Aug 27 11:28:17 localhost volumio[1145]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 27 11:28:17 localhost volumio[1145]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 27 11:28:17 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:28:17 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:28:17 localhost volumio[1145]: info: Starting MyVolumio Remote Streaming Endpoints Aug 27 11:28:18 localhost volumio[1145]: info: MyVolumio login type: Token Aug 27 11:28:18 localhost volumio[1145]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 27 11:28:18 localhost volumio[1145]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Aug 27 11:28:18 localhost volumio[1145]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 27 11:28:19 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 27 11:28:19 localhost volumio[1145]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 27 11:28:19 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 27 11:28:19 localhost volumio[1145]: info: Received Get System Version Aug 27 11:28:19 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 11:28:19 localhost volumio[1145]: info: Received Get System Info Aug 27 11:28:19 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 11:28:19 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 11:28:19 localhost volumio[1145]: info: Discovery: Getting this device information Aug 27 11:28:19 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:19 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:19 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 11:28:19 localhost volumio[1145]: info: MyVolumio token set successfully Aug 27 11:28:19 localhost volumio[1145]: info: MYVOLUMIO: Adding device Aug 27 11:28:19 localhost volumio[1145]: info: MYVOLUMIO: Evaluating Server Aug 27 11:28:19 localhost volumio[1145]: info: Discovery: adding 70a5060a-b4ab-4bd2-870b-06ef0104d8e7 Aug 27 11:28:19 localhost volumio[1145]: info: Discovery: Found device StreamPlayC0-dev0 Aug 27 11:28:19 localhost volumio[1145]: info: Discovery: Connecting to remote: 192.168.1.100 Aug 27 11:28:20 localhost volumio[1145]: info: MyVolumio status changed Aug 27 11:28:20 localhost volumio[1145]: info: Streaming services startup Aug 27 11:28:20 localhost volumio[1145]: info: Starting Streaming Daemon Aug 27 11:28:20 localhost volumio[1145]: info: Removing browser output: myVolumio user plan is not superstar Aug 27 11:28:20 localhost volumio[1145]: info: Removing audio output: Aug 27 11:28:20 localhost volumio[1145]: info: Stoppping Tunnel 1 Aug 27 11:28:20 localhost sudo[1754]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 27 11:28:20 localhost sudo[1754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:20 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Aug 27 11:28:20 localhost volumio[1145]: info: System name has changed, restarting Shairport Sync Aug 27 11:28:20 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:20 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:28:20 localhost sudo[1756]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 27 11:28:20 localhost sudo[1756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:20 localhost volumio[1145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 11:28:20 localhost volumio[1145]: info: QobuzConnect: setDeactiveState invoked Aug 27 11:28:20 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:20 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:20 localhost vtcs[1597]: [2026-08-27 11:28:20.511] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Aug 27 11:28:20 localhost vtcs[1597]: [2026-08-27 11:28:20.512] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer Aug 27 11:28:20 localhost vtcs[1597]: [2026-08-27 11:28:20.512] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Aug 27 11:28:20 localhost volumio[1145]: info: Updating MyVolumio device info Aug 27 11:28:20 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:28:20 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:28:20 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:28:20 localhost sudo[1778]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 11:28:20 localhost sudo[1778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:20 localhost sudo[1754]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:20 localhost volumio[1145]: info: Discovery: Restarting Advertising Aug 27 11:28:20 localhost volumio[1145]: info: Discovery: Stopping existing advertisement Aug 27 11:28:20 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Aug 27 11:28:20 localhost 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 27 11:28:20 localhost 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 27 11:28:20 localhost sudo[1781]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Aug 27 11:28:20 localhost sudo[1781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:20 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 11:28:20 localhost sudo[1781]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:20 localhost 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 27 11:28:20 localhost 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 27 11:28:20 localhost 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 27 11:28:20 localhost 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 27 11:28:20 localhost sudo[1756]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:20 localhost systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Aug 27 11:28:20 localhost sudo[1787]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 11:28:20 localhost sudo[1787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:20 localhost systemd[1]: vtcs.service: Killing process 1639 (n/a) with signal SIGKILL. Aug 27 11:28:20 localhost systemd[1]: vtcs.service: Deactivated successfully. Aug 27 11:28:20 localhost systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 27 11:28:20 localhost sudo[1794]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 27 11:28:20 localhost sudo[1794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:20 localhost sudo[1778]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:20 localhost volumio[1145]: info: Setting Geolocation for MyVolumio to eu7 Aug 27 11:28:20 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:28:20 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:28:20 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:28:20 localhost volumio[1145]: info: Received Get System Info Aug 27 11:28:20 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 11:28:20 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 11:28:20 localhost volumio[1145]: info: Discovery: Getting this device information Aug 27 11:28:20 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:20 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:20 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 11:28:20 localhost volumio[1145]: info: Received Get System Info Aug 27 11:28:20 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 11:28:20 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 11:28:20 localhost volumio[1145]: info: Discovery: Getting this device information Aug 27 11:28:20 localhost volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:20 localhost volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:20 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 11:28:20 localhost sudo[1787]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:20 localhost volumio[1145]: info: Remote SSH Stopped Aug 27 11:28:20 localhost volumio[1145]: error: Cannot start Volumio Streaming Daemon Aug 27 11:28:20 localhost volumio[1145]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 27 11:28:20 localhost volumio[1145]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 27 11:28:21 localhost sudo[1794]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:21 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 11:28:21 localhost volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 11:28:21 localhost sudo[1800]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 27 11:28:21 localhost sudo[1802]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Aug 27 11:28:21 localhost sudo[1802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:21 localhost volumio[1145]: info: Starting Shairport Sync Aug 27 11:28:21 localhost sudo[1800]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:21 localhost sudo[1802]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:21 localhost volumio[1145]: info: Permissions for /etc/hosts set Aug 27 11:28:21 localhost volumio5-onboarding[1588]: time=2026-08-27T11:28:21.081+02:00 level=INFO msg="device name changed, resetting service group" component=discovery/localnet Aug 27 11:28:21 localhost sudo[1806]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 11:28:21 localhost sudo[1806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:21 localhost volumio[1145]: info: Hostname now is streamplayc0-dev1 Aug 27 11:28:21 localhost sudo[1808]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname streamplayc0-dev1 Aug 27 11:28:21 localhost sudo[1808]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:21 streamplayc0-dev1 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Aug 27 11:28:21 streamplayc0-dev1 sudo[1808]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:21 streamplayc0-dev1 volumio[1145]: info: New hostname set Aug 27 11:28:21 streamplayc0-dev1 sudo[1810]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Aug 27 11:28:21 streamplayc0-dev1 sudo[1810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:21 streamplayc0-dev1 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 11:28:21 streamplayc0-dev1 sudo[1810]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:21 streamplayc0-dev1 systemd[1]: upmpdcli.service: Deactivated successfully. Aug 27 11:28:21 streamplayc0-dev1 systemd[1]: upmpdcli.service: Consumed 6.950s CPU time. Aug 27 11:28:21 streamplayc0-dev1 systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 11:28:21 streamplayc0-dev1 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 11:28:21 streamplayc0-dev1 systemd[1]: shairport-sync.service: Consumed 1.794s CPU time. Aug 27 11:28:21 streamplayc0-dev1 volumio[1145]: info: Permissions for /etc/avahi/services/volumio.service Aug 27 11:28:21 streamplayc0-dev1 volumio[1145]: info: Avahi name changed to streamplayc0-dev1 Aug 27 11:28:21 streamplayc0-dev1 avahi-daemon[505]: Files changed, reloading. Aug 27 11:28:21 streamplayc0-dev1 avahi-daemon[505]: Loading service file /services/volumio.service. Aug 27 11:28:21 streamplayc0-dev1 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 11:28:21 streamplayc0-dev1 sudo[1806]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:21 streamplayc0-dev1 qobuz-connect[1518]: 20260827 11:28:21.280 [1518.1518] INFO SampleApp: Stopping Local configuration server Aug 27 11:28:21 streamplayc0-dev1 volumio[1145]: info: Shairport-Sync Started Aug 27 11:28:21 streamplayc0-dev1 volumio[1145]: info: Successfully Added MyVolumio device Aug 27 11:28:21 streamplayc0-dev1 volumio[1145]: info: Successfully Updated MyVolumio device Aug 27 11:28:21 streamplayc0-dev1 volumio[1145]: info: Discovery: A device disappeared from network Aug 27 11:28:21 streamplayc0-dev1 volumio[1145]: info: Discovery: A device disappeared from network Aug 27 11:28:21 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 27 11:28:21 streamplayc0-dev1 volumio[1145]: info: Preparing to save Alsa Options, stopping services first Aug 27 11:28:21 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:21 streamplayc0-dev1 volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:21 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::volumioPause Aug 27 11:28:21 streamplayc0-dev1 volumio[1145]: info: CoreStateMachine::pause Aug 27 11:28:21 streamplayc0-dev1 volumio[1145]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"0","label":"Analog Outputs"},"disallowPush":true} Aug 27 11:28:21 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 11:28:22 streamplayc0-dev1 avahi-daemon[505]: Service "StreamPlayC0-dev1" (/services/volumio.service) successfully established. Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: Setting default mixerSoftMaster for Softvolume device Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: QobuzConnect: setDeactiveState invoked Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: Updating Volume Controller Parameters: Device: 0 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 27 11:28:22 streamplayc0-dev1 sudo[1839]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 11:28:22 streamplayc0-dev1 sudo[1839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: Disabling external Volume Control Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: Enable softmixer device for audio device undefined Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: Retrieving Cloud Streaming UI Aug 27 11:28:22 streamplayc0-dev1 sudo[1839]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:22 streamplayc0-dev1 qobuz-connect[1518]: 20260827 11:28:22.621 [1518.1518] INFO SampleApp: shat down connection on UNIX socket Aug 27 11:28:22 streamplayc0-dev1 sudo[1852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 11:28:22 streamplayc0-dev1 sudo[1852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:22 streamplayc0-dev1 systemd[1]: qobuz-connect.service: Deactivated successfully. Aug 27 11:28:22 streamplayc0-dev1 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: Updating MyVolumio device info Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:28:22 streamplayc0-dev1 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 27 11:28:22 streamplayc0-dev1 sudo[1860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 27 11:28:22 streamplayc0-dev1 sudo[1860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:22 streamplayc0-dev1 sudo[1800]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: CoreStateMachine::pushState Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::volumioPushState Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: MRS: Pushing multiroomSync output update for this device Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: MRS: Pushing multiroomSync output Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: QobuzConnect: setDeactiveState invoked Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:22 streamplayc0-dev1 sudo[1860]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: Software Volume ALSA configuration written Aug 27 11:28:22 streamplayc0-dev1 sudo[1852]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:22 streamplayc0-dev1 volumio[1145]: info: Preparing to generate the ALSA configuration file Aug 27 11:28:22 streamplayc0-dev1 sudo[1863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 27 11:28:22 streamplayc0-dev1 sudo[1863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:22 streamplayc0-dev1 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Aug 27 11:28:22 streamplayc0-dev1 systemd[1]: qobuz-connect.service: Deactivated successfully. Aug 27 11:28:22 streamplayc0-dev1 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Aug 27 11:28:23 streamplayc0-dev1 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 27 11:28:23 streamplayc0-dev1 sudo[1863]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:23 streamplayc0-dev1 volumio5-onboarding[1588]: time=2026-08-27T11:28:23.055+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: Getting Tidal Cloud Configuration Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: Getting Qobuz Cloud Configuration Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: Asking plugin for UI Config Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: Getting Spotify Cloud Configuration Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: Asking plugin for UI Config Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: Saving Spotify Acccount Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: Got it Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: Reading ALSA contributions from plugins. Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: Executing endpoint qc_getconfig Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: Executing endpoint qc_getconfig Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 27 11:28:23 streamplayc0-dev1 qobuz-connect[1866]: 20260827 11:28:23.529 [1866.1866] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Aug 27 11:28:23 streamplayc0-dev1 qobuz-connect[1866]: 20260827 11:28:23.540 [1866.1866] INFO VolumeManager: [0x73d148]: Setting new playback volume: 75 Aug 27 11:28:23 streamplayc0-dev1 qobuz-connect[1866]: 20260827 11:28:23.540 [1866.1866] INFO VolumeManager: [0x73d148]: Setting new mute state: 0 Aug 27 11:28:23 streamplayc0-dev1 qobuz-connect[1866]: 20260827 11:28:23.540 [1866.1866] INFO AudioStreamManager: [0x73cea0]: Setting new audio download buffer size: 1048576 Aug 27 11:28:23 streamplayc0-dev1 qobuz-connect[1866]: 20260827 11:28:23.540 [1866.1866] INFO QobuzConnect: [0x73da10]: Client initialized! Aug 27 11:28:23 streamplayc0-dev1 qobuz-connect[1866]: 20260827 11:28:23.540 [1866.1866] INFO SampleApp: Starting Avahi advertising, name: StreamPlayC0-dev1, service name: _qobuz-connect._tcp Aug 27 11:28:23 streamplayc0-dev1 qobuz-connect[1866]: 20260827 11:28:23.569 [1866.1866] INFO LocalConfigManager: [0x73cbc8]: Starting Local Configuration server Aug 27 11:28:23 streamplayc0-dev1 qobuz-connect[1866]: 20260827 11:28:23.569 [1866.1866] INFO SampleApp: Starting Local configuration server Aug 27 11:28:23 streamplayc0-dev1 qobuz-connect[1866]: 20260827 11:28:23.570 [1866.1866] INFO SampleApp: Connected to UNIX socket client 0x727908 Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: QobuzConnect: QOBUZ Connect daemon connected Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 sudo[1890]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Aug 27 11:28:23 streamplayc0-dev1 sudo[1890]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: Got it Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: Got Tidal Cloud Configuration Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: Retrieving Cloud Streaming UI Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: Asound.conf file unchanged, so no further update is needed Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: Output device has changed, restarting MPD Aug 27 11:28:23 streamplayc0-dev1 sudo[1890]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:23 streamplayc0-dev1 qobuz-connect[1866]: 20260827 11:28:23.679 [1866.1866] INFO SampleApp: Playback volume changed: 75 Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: Output device has changed, restarting Shairport Sync Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 sudo[1893]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Aug 27 11:28:23 streamplayc0-dev1 sudo[1894]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 11:28:23 streamplayc0-dev1 sudo[1894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:23 streamplayc0-dev1 sudo[1893]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:23 streamplayc0-dev1 sudo[1894]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:23 streamplayc0-dev1 sudo[1896]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 11:28:23 streamplayc0-dev1 sudo[1896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: QobuzConnect: setDeactiveState invoked Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:23 streamplayc0-dev1 systemd[1]: Started raat-daemon.service - RAAT DAEMON. Aug 27 11:28:23 streamplayc0-dev1 sudo[1908]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 11:28:23 streamplayc0-dev1 sudo[1908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:23 streamplayc0-dev1 sudo[1893]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:23 streamplayc0-dev1 sudo[1910]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 11:28:23 streamplayc0-dev1 sudo[1910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:23 streamplayc0-dev1 sudo[1921]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 27 11:28:23 streamplayc0-dev1 sudo[1921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:23 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::volumioGetState Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CorePlayQueue::getTrack 0 Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: MPD Permissions set Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: TidalConnect service stoped! Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: TidalConnect service stoped! Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: Raat Daemon started successfully Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: Successfully Updated MyVolumio device Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: Getting Tidal Cloud Configuration Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: Getting Qobuz Cloud Configuration Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: Asking plugin for UI Config Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: Getting Spotify Cloud Configuration Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: Asking plugin for UI Config Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: Saving Spotify Acccount Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: Got it Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 27 11:28:24 streamplayc0-dev1 sudo[1941]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 27 11:28:24 streamplayc0-dev1 sudo[1941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: Got Tidal Cloud Configuration Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: Got it Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: Starting Shairport Sync Aug 27 11:28:24 streamplayc0-dev1 sudo[1949]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 11:28:24 streamplayc0-dev1 sudo[1949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:24 streamplayc0-dev1 volumio-remote-updater[530]: Test mode disabled Aug 27 11:28:24 streamplayc0-dev1 volumio-remote-updater[530]: Alpha mode disabled Aug 27 11:28:24 streamplayc0-dev1 volumio-remote-updater[530]: Alpha legacy test mode disabled Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: error: MyVolumio Plugin failed to start in a timely fashion Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: [Metrics] CommandRouter: 70s 619.20ms Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::volumiosetStartupVolume Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::Close All Modals sent Aug 27 11:28:24 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::Close All Modals sent Aug 27 11:28:25 streamplayc0-dev1 sudo[1921]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:26 streamplayc0-dev1 sudo[1908]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Aug 27 11:28:26 streamplayc0-dev1 systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 27 11:28:26 streamplayc0-dev1 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Aug 27 11:28:26 streamplayc0-dev1 sudo[1910]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:26 streamplayc0-dev1 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 27 11:28:26 streamplayc0-dev1 systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 11:28:26 streamplayc0-dev1 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 11:28:26 streamplayc0-dev1 systemd[1]: shairport-sync.service: Consumed 1.711s CPU time. Aug 27 11:28:26 streamplayc0-dev1 sudo[1958]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 27 11:28:26 streamplayc0-dev1 sudo[1958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Aug 27 11:28:26 streamplayc0-dev1 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 11:28:26 streamplayc0-dev1 sudo[1941]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:26 streamplayc0-dev1 sudo[1949]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: Retrieving Cloud Streaming UI Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: Retrieving Cloud Streaming UI Aug 27 11:28:26 streamplayc0-dev1 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Aug 27 11:28:26 streamplayc0-dev1 qobuz-connect[1866]: 20260827 11:28:26.236 [1866.1866] INFO SampleApp: Stopping Local configuration server Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: TidalConnect service stoped! Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: TidalConnect service stoped! Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: Discovery: Started advertising with name: StreamPlayC0-dev1 Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::Reload Ui Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: Wizard terminated Successfully Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::volumiosetStartupVolume Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: info: Shairport-Sync Started Aug 27 11:28:26 streamplayc0-dev1 sudo[1998]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Aug 27 11:28:26 streamplayc0-dev1 sudo[1998]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:26 streamplayc0-dev1 sudo[1998]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: Job for vtcs.service canceled. Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: {"cmd":"/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3\nJob for vtcs.service canceled.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)","stderr":"Job for vtcs.service canceled.\n","stdout":""} Aug 27 11:28:26 streamplayc0-dev1 sudo[2004]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Aug 27 11:28:26 streamplayc0-dev1 systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: Job for vtcs.service canceled. Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: at ChildProcess.exithandler (node:child_process:421:12) Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: at ChildProcess.emit (node:events:514:28) Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: at maybeClose (node:internal/child_process:1105:16) Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: at Socket. (node:internal/child_process:457:11) Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: at Socket.emit (node:events:514:28) Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: at Pipe. (node:net:337:12) { Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: code: 1, Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: killed: false, Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: signal: null, Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: stdout: '', Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: stderr: 'Job for vtcs.service canceled.\n' Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: } Aug 27 11:28:26 streamplayc0-dev1 volumio[1145]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 11:28:26 streamplayc0-dev1 sudo[2004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:26 streamplayc0-dev1 systemd[1]: raat-daemon.service: Deactivated successfully. Aug 27 11:28:26 streamplayc0-dev1 systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Aug 27 11:28:26 streamplayc0-dev1 systemd[1]: raat-daemon.service: Consumed 2.086s CPU time. Aug 27 11:28:26 streamplayc0-dev1 systemd[1]: Started raat-daemon.service - RAAT DAEMON. Aug 27 11:28:26 streamplayc0-dev1 sudo[2004]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:27 streamplayc0-dev1 qobuz-connect[1866]: 20260827 11:28:27.591 [1866.1866] INFO SampleApp: shat down connection on UNIX socket Aug 27 11:28:27 streamplayc0-dev1 systemd[1]: qobuz-connect.service: Deactivated successfully. Aug 27 11:28:27 streamplayc0-dev1 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Aug 27 11:28:27 streamplayc0-dev1 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 27 11:28:27 streamplayc0-dev1 sudo[1958]: pam_unix(sudo:session): session closed for user root Aug 27 11:28:31 streamplayc0-dev1 sudo[2044]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 11:27' Aug 27 11:28:31 streamplayc0-dev1 sudo[2044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 11:28:31 streamplayc0-dev1 systemd[1]: mpd.service: Deactivated successfully. Aug 27 11:28:31 streamplayc0-dev1 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 11:28:31 streamplayc0-dev1 systemd[1]: mpd.service: Consumed 10.417s CPU time. Aug 27 11:28:31 streamplayc0-dev1 systemd[1]: mpd.socket: Deactivated successfully. Aug 27 11:28:31 streamplayc0-dev1 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 11:28:31 streamplayc0-dev1 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 11:28:31 streamplayc0-dev1 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 11:28:31 streamplayc0-dev1 systemd[1]: Starting mpd.service - Music Player Daemon... 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="ad2b65e62ee66106fc9799a5d56d019303babfad" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="9938d7179e3b7c4e41f3e2d60c255985cff08fee" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="spc0vara" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Aug 5 10:59:35 UTC 2026" VOLUMIO_VERSION="4.187" VOLUMIO_HARDWARE="cm0" VOLUMIO_DEVICENAME="CM0" VOLUMIO_HASH="630daf9914936ac794120944272a709e"