May 26 15:04:00 studio ntpd[858]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 May 26 15:04:00 studio bluetoothd[777]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled May 26 15:04:00 studio bluetoothd[777]: bap: Operation not supported (95) May 26 15:04:00 studio bluetoothd[777]: Adv Monitor Manager created with supported features:0x00000000, enabled features:0x00000000, max number of supported monitors:32, max number of supported patterns:16 May 26 15:04:00 studio ntpd[858]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 May 26 15:04:00 studio ntpd[858]: DNS: dns_check: DNS error: -11, System error May 26 15:04:00 studio ntpd[858]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 May 26 15:04:01 studio bluealsa[884]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa May 26 15:04:01 studio bluealsa[884]: main.c:663: Starting main dispatching loop May 26 15:04:01 studio systemd[1]: Started bluealsa.service - BlueALSA service. May 26 15:04:01 studio bluealsa[884]: main.c:118: Acquired D-Bus service name: org.bluealsa May 26 15:04:01 studio systemd[1]: Reached target bluetooth.target - Bluetooth Support. May 26 15:04:01 studio bluealsa[884]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa May 26 15:04:01 studio bluealsa[884]: bluez.c:153: Registering media application: /org/bluez/hci0 May 26 15:04:01 studio bluealsa[884]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 May 26 15:04:01 studio bluealsa[884]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 May 26 15:04:01 studio bluealsa[884]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 May 26 15:04:01 studio bluealsa[884]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 May 26 15:04:01 studio bluetoothd[777]: Endpoint registered: sender=:1.7 path=/org/bluez/hci0/A2DP/SBC/source/1 May 26 15:04:01 studio bluetoothd[777]: Endpoint registered: sender=:1.7 path=/org/bluez/hci0/A2DP/SBC/sink/2 May 26 15:04:01 studio bluetoothd[777]: Endpoint registered: sender=:1.7 path=/org/bluez/hci0/A2DP/SBC/source/2 May 26 15:04:01 studio systemd[1]: systemd-rfkill.service: Deactivated successfully. May 26 15:04:01 studio bluetoothd[777]: Endpoint registered: sender=:1.7 path=/org/bluez/hci0/A2DP/SBC/sink/1 May 26 15:04:01 studio ntpd[858]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 May 26 15:04:01 studio ntpd[858]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 May 26 15:04:01 studio ntpd[858]: DNS: dns_check: DNS error: -11, System error May 26 15:04:01 studio ntpd[858]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 May 26 15:04:02 studio volumio-remote-updater[647]: [2026-05-26 15:04:02] [info] asio async_connect error: asio.system:111 (Connection refused) May 26 15:04:02 studio volumio-remote-updater[647]: [2026-05-26 15:04:02] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) May 26 15:04:02 studio volumio-remote-updater[647]: [2026-05-26 15:04:02] [error] handle_connect error: Connection refused May 26 15:04:02 studio ntpd[858]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 May 26 15:04:02 studio ntpd[858]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 May 26 15:04:02 studio ntpd[858]: DNS: dns_check: DNS error: -11, System error May 26 15:04:02 studio ntpd[858]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 May 26 15:04:02 studio systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. May 26 15:04:02 studio systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. May 26 15:04:03 studio ntpd[858]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 May 26 15:04:03 studio ntpd[858]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 May 26 15:04:03 studio ntpd[858]: DNS: dns_check: DNS error: -11, System error May 26 15:04:03 studio ntpd[858]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 May 26 15:04:03 studio nmbd[939]: [2026/05/26 15:04:03.664044, 0] ../../source3/nmbd/nmbd.c:901(main) May 26 15:04:03 studio nmbd[939]: nmbd version 4.17.8-Raspbian started. May 26 15:04:03 studio nmbd[939]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 26 15:04:03 studio nmbd[939]: [2026/05/26 15:04:03.669674, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) May 26 15:04:03 studio nmbd[939]: started asyncdns process 942 May 26 15:04:03 studio nmbd[939]: [2026/05/26 15:04:03.671940, 0] ../../lib/util/become_daemon.c:150(daemon_status) May 26 15:04:03 studio nmbd[939]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... May 26 15:04:03 studio nmbd[939]: [2026/05/26 15:04:03.672099, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) May 26 15:04:03 studio nmbd[939]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). May 26 15:04:04 studio wireless.js[648]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless May 26 15:04:04 studio wireless.js[648]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow May 26 15:04:05 studio sudo[966]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 26 15:04:05 studio sudo[966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 15:04:05 studio sudo[966]: pam_unix(sudo:session): session closed for user root May 26 15:04:05 studio sudo[969]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 26 15:04:05 studio sudo[969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 15:04:05 studio sudo[969]: pam_unix(sudo:session): session closed for user root May 26 15:04:05 studio wireless.js[648]: WIRELESS.JS - INFO: Cleaning previous... May 26 15:04:05 studio sudo[972]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 26 15:04:05 studio sudo[972]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 15:04:05 studio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 26 15:04:05 studio sudo[972]: pam_unix(sudo:session): session closed for user root May 26 15:04:05 studio wireless.js[648]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations May 26 15:04:05 studio wireless.js[648]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 4ms May 26 15:04:05 studio wireless.js[648]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: b8:27:eb:49:fc:3e) May 26 15:04:05 studio sudo[979]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 26 15:04:05 studio sudo[979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:04:05 studio sudo[979]: pam_unix(sudo:session): session closed for user root May 26 15:04:05 studio sudo[987]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 26 15:04:05 studio sudo[987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:04:08 studio sudo[987]: pam_unix(sudo:session): session closed for user root May 26 15:04:08 studio wireless.js[648]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: IT May 26 15:04:08 studio sudo[1011]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set IT May 26 15:04:08 studio sudo[1011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:04:08 studio sudo[1011]: pam_unix(sudo:session): session closed for user root May 26 15:04:08 studio wireless.js[648]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: IT May 26 15:04:08 studio wireless.js[648]: WIRELESS.JS - INFO: Start wireless flow May 26 15:04:08 studio wireless.js[648]: WIRELESS.JS - INFO: Stopped hotspot (if there).. May 26 15:04:08 studio sudo[1020]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 26 15:04:08 studio sudo[1020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 15:04:08 studio sudo[1020]: pam_unix(sudo:session): session closed for user root May 26 15:04:08 studio sudo[1022]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 26 15:04:08 studio sudo[1022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 15:04:09 studio bash[830]: setdatetime-helper: all HTTPS Date fallbacks failed May 26 15:04:09 studio systemd[1]: setdatetime-helper.service: Deactivated successfully. May 26 15:04:09 studio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 26 15:04:09 studio systemd[1]: setdatetime-helper.service: Consumed 1.704s CPU time. May 26 15:04:09 studio sudo[1022]: pam_unix(sudo:session): session closed for user root May 26 15:04:09 studio wireless.js[648]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations May 26 15:04:09 studio wireless.js[648]: WIRELESS.JS - INFO: STAGE 1: wlan0 validated and ready (MAC: b8:27:eb:49:fc:3e, USB: false) May 26 15:04:09 studio wpa_supplicant[1029]: Successfully initialized wpa_supplicant May 26 15:04:09 studio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 26 15:04:10 studio wpa_supplicant[1029]: nl80211: kernel reports: Registration to specific type not supported May 26 15:04:10 studio volumio-remote-updater[647]: [2026-05-26 15:04:10] [info] asio async_connect error: asio.system:111 (Connection refused) May 26 15:04:10 studio volumio-remote-updater[647]: [2026-05-26 15:04:10] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) May 26 15:04:10 studio volumio-remote-updater[647]: [2026-05-26 15:04:10] [error] handle_connect error: Connection refused May 26 15:04:10 studio sudo[1035]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 26 15:04:10 studio sudo[1035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 15:04:10 studio sudo[1035]: pam_unix(sudo:session): session closed for user root May 26 15:04:11 studio wireless.js[648]: WIRELESS.JS - INFO: DHCP IP fallback May 26 15:04:11 studio wireless.js[648]: WIRELESS.JS - INFO: STAGE 2: Starting event-driven WPA state monitor May 26 15:04:11 studio wireless.js[648]: WIRELESS.JS - INFO: WpaStateMachine: Starting state monitor for wlan0 May 26 15:04:11 studio wireless.js[648]: WIRELESS.JS - INFO: WpaStateMachine: State transition: NULL -> SCANNING (duration: 0ms) May 26 15:04:12 studio wpa_supplicant[1032]: wlan0: Trying to associate with 00:7a:a4:5e:f3:6f (SSID='Sole_5G' freq=5180 MHz) May 26 15:04:12 studio wpa_supplicant[1032]: wlan0: Associated with 00:7a:a4:5e:f3:6f May 26 15:04:12 studio wpa_supplicant[1032]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 26 15:04:12 studio wpa_supplicant[1032]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=IT May 26 15:04:12 studio wpa_supplicant[1032]: wlan0: WPA: Key negotiation completed with 00:7a:a4:5e:f3:6f [PTK=CCMP GTK=CCMP] May 26 15:04:12 studio wpa_supplicant[1032]: wlan0: CTRL-EVENT-CONNECTED - Connection to 00:7a:a4:5e:f3:6f completed [id=0 id_str=] May 26 15:04:12 studio dhcpcd[705]: wlan0: carrier acquired May 26 15:04:12 studio dhcpcd[705]: wlan0: connected to Access Point: Sole_5G May 26 15:04:12 studio dhcpcd[705]: wlan0: IAID eb:49:fc:3e May 26 15:04:12 studio dhcpcd[705]: wlan0: adding address fe80::65a8:d41a:67d3:864b May 26 15:04:12 studio dhcpcd[705]: ipv6_addaddr1: Permission denied May 26 15:04:12 studio wireless.js[648]: WIRELESS.JS - INFO: WpaStateMachine: State transition: SCANNING -> COMPLETED (duration: 1034ms) May 26 15:04:12 studio wireless.js[648]: WIRELESS.JS - INFO: WpaStateMachine: COMPLETED - connection successful May 26 15:04:12 studio wireless.js[648]: WIRELESS.JS - INFO: STAGE 2: Connection successful - Connected to 00:7a:a4:5e:f3:6f May 26 15:04:12 studio wireless.js[648]: WIRELESS.JS - INFO: Onboard WiFi adapter detected, using standard dhcpcd flow May 26 15:04:12 studio dhcpcd[705]: wlan0: rebinding lease of 192.168.0.10 May 26 15:04:13 studio dhcpcd[705]: wlan0: probing address 192.168.0.10/24 May 26 15:04:13 studio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. May 26 15:04:13 studio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 26 15:04:13 studio dhcpcd[705]: wlan0: soliciting an IPv6 router May 26 15:04:13 studio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 26 15:04:13 studio upmpdcli[1058]: Could not open config: /tmp/upmpdcli.conf May 26 15:04:13 studio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 26 15:04:13 studio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 26 15:04:13 studio sudo[1059]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd wlan0 May 26 15:04:13 studio sudo[1059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 15:04:13 studio dhcpcd[705]: control command: /sbin/dhcpcd wlan0 May 26 15:04:13 studio dhcpcd[705]: control_free: No such file or directory May 26 15:04:13 studio sudo[1059]: pam_unix(sudo:session): session closed for user root May 26 15:04:15 studio wireless.js[648]: WIRELESS.JS - INFO: Start ap May 26 15:04:16 studio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 26 15:04:16 studio wireless.js[648]: WIRELESS.JS - INFO: Notified systemd about wireless ready May 26 15:04:16 studio systemd[1]: Started wireless.service - Wireless Services. May 26 15:04:16 studio systemd[1]: Started volumio.service - Volumio Backend Module. May 26 15:04:16 studio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... May 26 15:04:16 studio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. May 26 15:04:16 studio systemd[1]: Received SIGRTMIN+21 from PID 290 (plymouthd). May 26 15:04:16 studio systemd[1]: Received SIGRTMIN+21 from PID 290 (plymouthd). May 26 15:04:16 studio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. May 26 15:04:16 studio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. May 26 15:04:16 studio systemd[1]: Started getty@tty1.service - Getty on tty1. May 26 15:04:16 studio systemd[1]: Reached target getty.target - Login Prompts. May 26 15:04:16 studio volumio-cpu-tweak[1070]: Setting RT Priority for mpd May 26 15:04:16 studio volumio-cpu-tweak[1085]: pid 35's current scheduling policy: SCHED_OTHER May 26 15:04:16 studio volumio-cpu-tweak[1085]: pid 35's current scheduling priority: 0 May 26 15:04:16 studio volumio-cpu-tweak[1070]: Setting MPD Affinity May 26 15:04:16 studio volumio-cpu-tweak[1086]: pid 3's current affinity mask: f May 26 15:04:16 studio volumio-cpu-tweak[1070]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 26 15:04:16 studio volumio-cpu-tweak[1070]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 26 15:04:16 studio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. May 26 15:04:16 studio wireless.js[648]: WIRELESS.JS - INFO: trying... May 26 15:04:17 studio sudo[1100]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 26 15:04:17 studio sudo[1100]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:04:17 studio sudo[1100]: pam_unix(sudo:session): session closed for user root May 26 15:04:17 studio sudo[1104]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 15:04:17 studio sudo[1104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 15:04:17 studio sudo[1104]: pam_unix(sudo:session): session closed for user root May 26 15:04:17 studio wireless.js[648]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined May 26 15:04:17 studio dhcpcd[705]: wlan0: leased 192.168.0.10 for 30240000 seconds May 26 15:04:17 studio avahi-daemon[626]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.10. May 26 15:04:17 studio avahi-daemon[626]: New relevant interface wlan0.IPv4 for mDNS. May 26 15:04:17 studio avahi-daemon[626]: Registering new address record for 192.168.0.10 on wlan0.IPv4. May 26 15:04:17 studio dhcpcd[705]: wlan0: adding route to 192.168.0.0/24 May 26 15:04:17 studio dhcpcd[705]: wlan0: adding default route via 192.168.0.1 May 26 15:04:17 studio systemd[1]: welcome.service: Deactivated successfully. May 26 15:04:17 studio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 26 15:04:17 studio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 26 15:04:17 studio systemd[1]: Starting welcome.service - Show a welcome message on console... May 26 15:04:17 studio welcome[1124]: Resolved ip:[1] 192.168.0.10 May 26 15:04:17 studio systemd[1]: Finished welcome.service - Show a welcome message on console. May 26 15:04:17 studio systemd[1]: Started nmbd.service - Samba NMB Daemon. May 26 15:04:17 studio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 26 15:04:17 studio nmbd[939]: [2026/05/26 15:04:17.788535, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 26 15:04:17 studio nmbd[939]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.10 for name WORKGROUP<1d>. May 26 15:04:17 studio nmbd[939]: This response was from IP 192.168.0.4, reporting an IP address of 192.168.0.4. May 26 15:04:17 studio wireless.js[648]: WIRELESS.JS - INFO: trying... May 26 15:04:17 studio systemd[1]: Starting winbind.service - Samba Winbind Daemon... May 26 15:04:18 studio sudo[1153]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 26 15:04:18 studio sudo[1153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:04:18 studio sudo[1153]: pam_unix(sudo:session): session closed for user root May 26 15:04:18 studio sudo[1156]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 15:04:18 studio sudo[1156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 15:04:18 studio sudo[1156]: pam_unix(sudo:session): session closed for user root May 26 15:04:18 studio wireless.js[648]: WIRELESS.JS - INFO: ... wlan0 IPv4 is 192.168.0.10, ipV6 is undefined May 26 15:04:18 studio wireless.js[648]: WIRELESS.JS - INFO: Connected to SSID: Sole_5G May 26 15:04:18 studio wireless.js[648]: WIRELESS.JS - INFO: It's done! AP May 26 15:04:18 studio wireless.js[648]: WIRELESS.JS - INFO: Restarting avahi-daemon... May 26 15:04:18 studio sudo[1161]: root : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart avahi-daemon May 26 15:04:18 studio sudo[1161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 15:04:18 studio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 15:04:18 studio systemd[1]: shairport-sync.service: Deactivated successfully. May 26 15:04:18 studio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 15:04:18 studio systemd[1]: shairport-sync.service: Consumed 1.811s CPU time. May 26 15:04:18 studio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... May 26 15:04:18 studio avahi-daemon[626]: Got SIGTERM, quitting. May 26 15:04:18 studio avahi-daemon[626]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.10. May 26 15:04:18 studio avahi-daemon[626]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. May 26 15:04:18 studio avahi-daemon[626]: avahi-daemon 0.8 exiting. May 26 15:04:18 studio systemd[1]: avahi-daemon.service: Deactivated successfully. May 26 15:04:18 studio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. May 26 15:04:18 studio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... May 26 15:04:18 studio ntpd[858]: IO: Listen normally on 3 wlan0 192.168.0.10:123 May 26 15:04:18 studio ntpd[858]: IO: new interface(s) found: waking up resolver May 26 15:04:18 studio ntpd[858]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 May 26 15:04:18 studio avahi-daemon[1163]: Process 626 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) May 26 15:04:18 studio ntpd[858]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 May 26 15:04:18 studio ntpd[858]: DNS: Pool taking: 204.216.214.76 May 26 15:04:18 studio ntpd[858]: DNS: Pool taking: 5.231.80.8 May 26 15:04:18 studio ntpd[858]: DNS: Pool taking: 151.22.209.90 May 26 15:04:18 studio ntpd[858]: DNS: Pool taking: 93.94.88.50 May 26 15:04:18 studio ntpd[858]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 May 26 15:04:18 studio avahi-daemon[1163]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). May 26 15:04:18 studio avahi-daemon[1163]: Successfully dropped root privileges. May 26 15:04:18 studio avahi-daemon[1163]: avahi-daemon 0.8 starting up. May 26 15:04:18 studio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. May 26 15:04:18 studio avahi-daemon[1163]: Successfully called chroot(). May 26 15:04:18 studio avahi-daemon[1163]: Successfully dropped remaining capabilities. May 26 15:04:18 studio sudo[1161]: pam_unix(sudo:session): session closed for user root May 26 15:04:18 studio avahi-daemon[1163]: Loading service file /services/volumio.service. May 26 15:04:18 studio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 15:04:18 studio avahi-daemon[1163]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.10. May 26 15:04:18 studio avahi-daemon[1163]: New relevant interface wlan0.IPv4 for mDNS. May 26 15:04:18 studio avahi-daemon[1163]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. May 26 15:04:18 studio avahi-daemon[1163]: New relevant interface lo.IPv4 for mDNS. May 26 15:04:18 studio avahi-daemon[1163]: Network interface enumeration completed. May 26 15:04:18 studio avahi-daemon[1163]: Registering new address record for 192.168.0.10 on wlan0.IPv4. May 26 15:04:18 studio avahi-daemon[1163]: Registering new address record for 127.0.0.1 on lo.IPv4. May 26 15:04:18 studio wireless.js[648]: WIRELESS.JS - INFO: Notified systemd about wireless ready May 26 15:04:18 studio winbindd[1151]: [2026/05/26 15:04:18.946332, 0] ../../source3/winbindd/winbindd.c:1440(main) May 26 15:04:18 studio winbindd[1151]: winbindd version 4.17.8-Raspbian started. May 26 15:04:18 studio winbindd[1151]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 26 15:04:18 studio winbindd[1151]: [2026/05/26 15:04:18.980675, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) May 26 15:04:18 studio winbindd[1151]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 26 15:04:19 studio systemd[1]: Started winbind.service - Samba Winbind Daemon. May 26 15:04:19 studio systemd[1]: Starting smbd.service - Samba SMB Daemon... May 26 15:04:19 studio avahi-daemon[1163]: Server startup complete. Host name is studio.local. Local service cookie is 87865772. May 26 15:04:19 studio ntpd[858]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 May 26 15:04:19 studio ntpd[858]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 May 26 15:04:19 studio ntpd[858]: DNS: Pool taking: 85.199.214.99 May 26 15:04:19 studio ntpd[858]: DNS: Pool taking: 162.159.200.123 May 26 15:04:19 studio ntpd[858]: DNS: Pool taking: 129.152.16.145 May 26 15:04:19 studio ntpd[858]: DNS: Pool taking: 172.232.209.103 May 26 15:04:19 studio ntpd[858]: DNS: Pool taking: 2600:3c0b::f03c:94ff:fee2:cbb9 May 26 15:04:19 studio ntpd[858]: DNS: Pool taking: 2603:c020:8017:3e00:123:123:123:123 May 26 15:04:19 studio ntpd[858]: DNS: Pool taking: 2a00:6d41:200:2::12 May 26 15:04:19 studio ntpd[858]: DNS: Pool taking: 2a00:6d41:200:2::14 May 26 15:04:19 studio ntpd[858]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 May 26 15:04:19 studio smbd[1193]: [2026/05/26 15:04:19.974381, 0] ../../source3/smbd/server.c:1741(main) May 26 15:04:19 studio smbd[1193]: smbd version 4.17.8-Raspbian started. May 26 15:04:19 studio smbd[1193]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 26 15:04:20 studio volumio-remote-updater[647]: [2026-05-26 15:04:20] [info] asio async_connect error: asio.system:111 (Connection refused) May 26 15:04:20 studio volumio-remote-updater[647]: [2026-05-26 15:04:20] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) May 26 15:04:20 studio volumio-remote-updater[647]: [2026-05-26 15:04:20] [error] handle_connect error: Connection refused May 26 15:04:20 studio avahi-daemon[1163]: Service "Studio" (/services/volumio.service) successfully established. May 26 15:04:20 studio ntpd[858]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 May 26 15:04:20 studio ntpd[858]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 May 26 15:04:20 studio ntpd[858]: DNS: Pool taking: 195.231.84.187 May 26 15:04:20 studio ntpd[858]: DNS: Pool taking: 93.94.88.51 May 26 15:04:20 studio ntpd[858]: DNS: Pool taking: 172.232.208.229 May 26 15:04:20 studio ntpd[858]: DNS: Pool taking: 81.56.230.156 May 26 15:04:20 studio ntpd[858]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 May 26 15:04:21 studio ntpd[858]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 May 26 15:04:21 studio ntpd[858]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 May 26 15:04:21 studio ntpd[858]: DNS: Pool taking: 89.46.74.148 May 26 15:04:21 studio ntpd[858]: DNS: Pool skipping: 93.94.88.50 May 26 15:04:21 studio ntpd[858]: DNS: Pool taking: 31.14.133.122 May 26 15:04:21 studio ntpd[858]: DNS: Pool skipping: 93.94.88.51 May 26 15:04:21 studio ntpd[858]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 May 26 15:04:22 studio systemd[1]: Started smbd.service - Samba SMB Daemon. May 26 15:04:22 studio systemd[1]: Reached target multi-user.target - Multi-User System. May 26 15:04:22 studio systemd[1]: Reached target graphical.target - Graphical Interface. May 26 15:04:22 studio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... May 26 15:04:22 studio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. May 26 15:04:22 studio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. May 26 15:04:22 studio systemd[1]: Startup finished in 18.344s (kernel) + 33.601s (userspace) = 51.946s. May 26 15:04:23 studio systemd[1]: systemd-fsckd.service: Deactivated successfully. May 26 15:04:24 studio sshd[1113]: Accepted password for volumio from 192.168.0.16 port 50209 ssh2 May 26 15:04:24 studio sshd[1113]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) May 26 15:04:53 studio ntpd[858]: CLOCK: time stepped by 28.318952 May 26 15:04:53 studio ntpd[858]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 26 15:04:53 studio systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. May 26 15:04:53 studio systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... May 26 15:04:53 studio systemd-logind[642]: New session 1 of user volumio. May 26 15:04:53 studio systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. May 26 15:04:53 studio systemd[1]: Starting user@1000.service - User Manager for UID 1000... May 26 15:04:53 studio (systemd)[1226]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) May 26 15:04:53 studio volumio[1068]: info: ------------------------------------------- May 26 15:04:53 studio volumio[1068]: info: ----- Volumio3 ---- May 26 15:04:53 studio volumio[1068]: info: ------------------------------------------- May 26 15:04:53 studio volumio[1068]: info: ----- System startup ---- May 26 15:04:53 studio volumio[1068]: info: ------------------------------------------- May 26 15:04:54 studio systemd[1226]: Queued start job for default target default.target. May 26 15:04:54 studio systemd[1226]: Created slice app.slice - User Application Slice. May 26 15:04:54 studio systemd[1226]: Reached target paths.target - Paths. May 26 15:04:54 studio systemd[1226]: Reached target timers.target - Timers. May 26 15:04:54 studio systemd[1226]: Listening on dirmngr.socket - GnuPG network certificate management daemon. May 26 15:04:54 studio systemd[1226]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). May 26 15:04:54 studio systemd[1226]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). May 26 15:04:54 studio systemd[1226]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). May 26 15:04:54 studio systemd[1226]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. May 26 15:04:54 studio systemd[1226]: Reached target sockets.target - Sockets. May 26 15:04:54 studio systemd[1226]: Reached target basic.target - Basic System. May 26 15:04:54 studio systemd[1]: Started user@1000.service - User Manager for UID 1000. May 26 15:04:54 studio systemd[1226]: Started mpris-proxy.service - Bluetooth mpris proxy. May 26 15:04:54 studio systemd[1226]: Reached target default.target - Main User Target. May 26 15:04:54 studio systemd[1226]: Startup finished in 963ms. May 26 15:04:54 studio systemd[1]: Started session-1.scope - Session 1 of User volumio. May 26 15:04:54 studio mpris-proxy[1241]: Can't get on session bus May 26 15:04:54 studio systemd[1226]: mpris-proxy.service: Main process exited, code=exited, status=1/FAILURE May 26 15:04:54 studio systemd[1226]: mpris-proxy.service: Failed with result 'exit-code'. May 26 15:04:54 studio sshd[1113]: pam_env(sshd:session): deprecated reading of user environment enabled May 26 15:04:55 studio volumio[1068]: info: MYVOLUMIO Environment detected May 26 15:04:55 studio volumio[1068]: info: Plugin folders cleanup May 26 15:04:55 studio volumio[1068]: info: Scanning into folder /volumio/app/plugins/ May 26 15:04:55 studio volumio[1068]: info: Scanning category audio_interface May 26 15:04:55 studio volumio[1068]: info: Scanning category miscellanea May 26 15:04:55 studio volumio[1068]: info: Scanning category music_service May 26 15:04:55 studio volumio[1068]: info: Scanning category plugins.json May 26 15:04:55 studio volumio[1068]: info: Scanning category system_controller May 26 15:04:55 studio volumio[1068]: info: Scanning category user_interface May 26 15:04:55 studio volumio[1068]: info: Scanning into folder /data/plugins/ May 26 15:04:55 studio volumio[1068]: info: Plugin folders cleanup completed May 26 15:04:55 studio volumio[1068]: info: ------------------------------------------- May 26 15:04:55 studio volumio[1068]: info: ----- Core plugins startup ---- May 26 15:04:55 studio volumio[1068]: info: ------------------------------------------- May 26 15:04:55 studio volumio[1068]: info: Loading plugins from folder /volumio/app/plugins/ May 26 15:04:55 studio volumio[1068]: info: Adding plugin upnp to MyMusic Plugins May 26 15:04:55 studio volumio[1068]: info: Adding plugin airplay_emulation to MyMusic Plugins May 26 15:04:55 studio volumio[1068]: info: Adding plugin upnp_browser to MyMusic Plugins May 26 15:04:55 studio volumio[1068]: info: Loading plugins from folder /data/plugins/ May 26 15:04:55 studio volumio[1068]: info: Loading plugin "system"... May 26 15:04:55 studio volumio[1068]: info: Loading plugin "appearance"... May 26 15:04:57 studio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. May 26 15:04:57 studio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 26 15:04:57 studio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 26 15:04:57 studio upmpdcli[1261]: Could not open config: /tmp/upmpdcli.conf May 26 15:04:57 studio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 26 15:04:57 studio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 26 15:04:58 studio volumio-remote-updater[647]: [2026-05-26 15:04:58] [connect] Successful connection May 26 15:04:58 studio volumio[1068]: info: Loading plugin "network"... May 26 15:04:58 studio volumio[1068]: info: Refreshing Cached IP Addresses May 26 15:04:58 studio volumio[1068]: info: Loading plugin "services"... May 26 15:04:58 studio sudo[1264]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 26 15:04:58 studio sudo[1265]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 15:04:58 studio volumio[1068]: info: Loading plugin "volumio5onboarding"... May 26 15:04:58 studio sudo[1265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:04:58 studio sudo[1264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:04:58 studio sudo[1265]: pam_unix(sudo:session): session closed for user root May 26 15:04:58 studio sudo[1264]: pam_unix(sudo:session): session closed for user root May 26 15:04:58 studio sudo[1275]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 26 15:04:58 studio sudo[1275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:04:58 studio volumio[1068]: info: Loading plugin "alsa_controller"... May 26 15:04:58 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 15:04:58 studio volumio[1068]: info: Loading plugin "wizard"... May 26 15:04:58 studio volumio[1068]: info: Loading plugin "networkfs"... May 26 15:04:59 studio volumio[1068]: info: Starting Udev Watcher for removable devices May 26 15:04:59 studio volumio[1068]: info: Ignoring mount for partition: boot May 26 15:04:59 studio volumio[1068]: info: Ignoring mount for partition: volumio May 26 15:04:59 studio volumio[1068]: info: Ignoring mount for partition: volumio_data May 26 15:04:59 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 26 15:04:59 studio volumio[1068]: info: Loading plugin "volumio_command_line_client"... May 26 15:04:59 studio sudo[1301]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=claudio,password=laura,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.0.4/3TB/dischi /mnt/NAS/cantina May 26 15:04:59 studio sudo[1301]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:04:59 studio volumio[1068]: info: Loading plugin "upnp"... May 26 15:04:59 studio volumio[1068]: info: [1779800699123] Starting Upmpd Daemon May 26 15:04:59 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 26 15:04:59 studio volumio[1068]: info: Loading plugin "my_music"... May 26 15:04:59 studio volumio[1068]: info: Loading plugin "mpd"... May 26 15:04:59 studio systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 26 15:04:59 studio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... May 26 15:04:59 studio kernel: netfs: FS-Cache loaded May 26 15:04:59 studio kernel: Key type cifs.spnego registered May 26 15:04:59 studio kernel: Key type cifs.idmap registered May 26 15:04:59 studio kernel: CIFS: Attempting to mount //192.168.0.4/3TB/dischi May 26 15:05:00 studio volumio[1068]: info: Loading plugin "upnp_browser"... May 26 15:05:00 studio sudo[1301]: pam_unix(sudo:session): session closed for user root May 26 15:05:02 studio sudo[1275]: pam_unix(sudo:session): session closed for user root May 26 15:05:03 studio volumio[1068]: info: Starting UPNP Browser May 26 15:05:03 studio volumio[1068]: info: Loading plugin "alarm-clock"... May 26 15:05:03 studio volumio[1068]: info: Loading plugin "airplay_emulation"... May 26 15:05:03 studio volumio[1068]: info: Starting Shairport Sync May 26 15:05:03 studio volumio[1068]: info: Loading plugin "last_100"... May 26 15:05:03 studio volumio[1068]: info: Loading plugin "webradio"... May 26 15:05:03 studio volumio[1068]: info: Loading plugin "i2s_dacs"... May 26 15:05:03 studio volumio[1068]: info: Loading plugin "volumiodiscovery"... May 26 15:05:04 studio volumio[1068]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 26 15:05:04 studio volumio[1068]: *** WARNING *** Please fix your application to use the native API of Avahi! May 26 15:05:04 studio volumio[1068]: *** WARNING *** For more information see May 26 15:05:04 studio volumio[1068]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 26 15:05:04 studio volumio[1068]: *** WARNING *** Please fix your application to use the native API of Avahi! May 26 15:05:04 studio volumio[1068]: *** WARNING *** For more information see May 26 15:05:04 studio node[1068]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 26 15:05:04 studio node[1068]: *** WARNING *** Please fix your application to use the native API of Avahi! May 26 15:05:04 studio node[1068]: *** WARNING *** For more information see May 26 15:05:04 studio node[1068]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 26 15:05:04 studio node[1068]: *** WARNING *** Please fix your application to use the native API of Avahi! May 26 15:05:04 studio node[1068]: *** WARNING *** For more information see May 26 15:05:04 studio volumio[1068]: info: Applying required configuration parameters for plugin volumiodiscovery May 26 15:05:04 studio volumio[1068]: info: Discovery: Started advertising with name: Studio May 26 15:05:04 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 26 15:05:04 studio volumio[1068]: info: Loading plugin "outputs"... May 26 15:05:04 studio volumio[1068]: info: Loading plugin "albumart"... May 26 15:05:04 studio volumio[1068]: info: Plugin example_plugin is not enabled May 26 15:05:04 studio volumio[1068]: info: Loading plugin "inputs"... May 26 15:05:04 studio volumio[1068]: info: Loading plugin "updater_comm"... May 26 15:05:04 studio systemd[1]: setdatetime-helper.service: Deactivated successfully. May 26 15:05:04 studio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 26 15:05:04 studio systemd[1]: setdatetime-helper.service: Consumed 1.427s CPU time. May 26 15:05:04 studio volumio[1068]: info: Plugin mpdemulation is not enabled May 26 15:05:04 studio volumio[1068]: info: Loading plugin "rest_api"... May 26 15:05:04 studio volumio[1068]: info: Loading plugin "websocket"... May 26 15:05:04 studio volumio[1068]: info: Starting Socket.io Server version 1.7.4 May 26 15:05:04 studio volumio[1068]: info: Loading i18n strings for locale it May 26 15:05:04 studio volumio[1068]: Updating browse sources language May 26 15:05:04 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 15:05:05 studio volumio[1363]: Forking 3 albumart workers May 26 15:05:05 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 15:05:05 studio volumio[1068]: info: CoreCommandRouter::initPlayerControls May 26 15:05:05 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:05 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:05 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:05 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:05 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:05 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:05 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:05 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:05 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 15:05:05 studio volumio[1068]: Express server listening on port 3000 May 26 15:05:05 studio volumio[1068]: [Metrics] WebUI: 14s 97.57ms May 26 15:05:05 studio volumio[1068]: info: CoreStateMachine::resetVolumioState May 26 15:05:05 studio volumio[1068]: info: CoreStateMachine::getcurrentVolume May 26 15:05:05 studio volumio[1068]: info: CoreCommandRouter::volumioRetrievevolume May 26 15:05:06 studio volumio[1068]: info: Volumio Network Manager: Network status updated: 2 May 26 15:05:06 studio volumio[1068]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.16 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 May 26 15:05:06 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:05:06 studio volumio[1068]: info: Executing start script for DAC IQaudIO DAC Plus May 26 15:05:06 studio volumio[1068]: info: Reloading queue from file May 26 15:05:07 studio volumio[1068]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.16 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 2 May 26 15:05:07 studio volumio[1068]: info: VolumeController:: Volume=100 Mute =false May 26 15:05:07 studio volumio[1068]: info: CoreStateMachine::pushState May 26 15:05:07 studio volumio[1068]: info: CorePlayQueue::getTrack 0 May 26 15:05:07 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 15:05:07 studio volumio[1068]: info: CoreCommandRouter::volumioPushState May 26 15:05:07 studio volumio[1068]: info: CoreStateMachine::updateTrackBlock May 26 15:05:07 studio volumio[1068]: info: CorePlayQueue::getTrackBlock May 26 15:05:07 studio volumio[1068]: info: CoreCommandRouter::volumioRetrievevolume May 26 15:05:07 studio volumio[1068]: info: CoreStateMachine::setRepeat null single undefined May 26 15:05:07 studio volumio[1068]: info: CoreStateMachine::pushState May 26 15:05:07 studio volumio[1068]: info: CorePlayQueue::getTrack 0 May 26 15:05:07 studio volumio[1068]: info: CoreCommandRouter::volumioPushState May 26 15:05:07 studio volumio[1068]: info: CoreStateMachine::setRandom null May 26 15:05:07 studio volumio[1068]: info: CoreStateMachine::pushState May 26 15:05:07 studio volumio[1068]: info: CorePlayQueue::getTrack 0 May 26 15:05:07 studio volumio[1068]: info: CoreCommandRouter::volumioPushState May 26 15:05:07 studio volumio[1068]: info: Setting Device type: Raspberry PI May 26 15:05:07 studio volumio[1068]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.16 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 May 26 15:05:07 studio volumio[1068]: info: DAC script executed May 26 15:05:07 studio volumio[1068]: info: Completed loading Core Plugins May 26 15:05:07 studio volumio[1068]: info: Preparing to generate the ALSA configuration file May 26 15:05:08 studio volumio[1068]: info: Discovery: adding 3b577b02-4909-44b6-a3de-bfe26ca16002 May 26 15:05:08 studio volumio[1068]: info: Discovery: Found device Studio May 26 15:05:08 studio volumio[1068]: info: CoreCommandRouter::volumioGetState May 26 15:05:08 studio volumio[1068]: info: CorePlayQueue::getTrack 0 May 26 15:05:08 studio volumio[1068]: info: Asound.conf file unchanged, so no further update is needed May 26 15:05:08 studio volumio[1068]: info: Output device has changed, restarting MPD May 26 15:05:08 studio volumio[1068]: info: Output device has changed, restarting Shairport Sync May 26 15:05:08 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:08 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:05:08 studio sudo[1429]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 15:05:08 studio sudo[1429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:05:08 studio sudo[1432]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 15:05:08 studio sudo[1432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:05:08 studio volumio[1068]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 15:05:08 studio volumio[1068]: info: ___________ START PLUGINS ___________ May 26 15:05:08 studio sudo[1429]: pam_unix(sudo:session): session closed for user root May 26 15:05:08 studio volumio[1068]: info: ControllerMpd::onStart: Initializing MPD May 26 15:05:08 studio volumio[1068]: info: Creating MPD Configuration file May 26 15:05:08 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 15:05:08 studio volumio[1068]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 15:05:08 studio volumio[1068]: info: [1779800708384] CoreMusicLibrary::Adding element Server multimediali May 26 15:05:08 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 15:05:08 studio sudo[1439]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service May 26 15:05:08 studio sudo[1439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:05:08 studio volumio[1068]: info: UPNP Browser: Client initialized successfully May 26 15:05:08 studio sudo[1441]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 15:05:08 studio sudo[1441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:05:08 studio sudo[1441]: pam_unix(sudo:session): session closed for user root May 26 15:05:08 studio sudo[1443]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 15:05:08 studio sudo[1443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:05:08 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:08 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:05:08 studio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 26 15:05:08 studio systemd[1]: Starting mpd.service - Music Player Daemon... May 26 15:05:08 studio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. May 26 15:05:08 studio sudo[1439]: pam_unix(sudo:session): session closed for user root May 26 15:05:08 studio systemd[1]: mpd.service: Deactivated successfully. May 26 15:05:08 studio systemd[1]: Stopped mpd.service - Music Player Daemon. May 26 15:05:08 studio systemd[1]: mpd.socket: Deactivated successfully. May 26 15:05:08 studio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 26 15:05:08 studio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 26 15:05:08 studio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 26 15:05:08 studio systemd[1]: Starting mpd.service - Music Player Daemon... May 26 15:05:08 studio volumio[1068]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 15:05:08 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:08 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:05:08 studio volumio[1068]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 15:05:09 studio volumio[1068]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 15:05:09 studio volumio[1068]: info: [1779800709003] CoreMusicLibrary::Adding element Last_100 May 26 15:05:09 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 15:05:09 studio volumio[1068]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 15:05:09 studio volumio[1068]: info: [1779800709092] CoreMusicLibrary::Adding element Webradio May 26 15:05:09 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 15:05:09 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 15:05:09 studio volumio[1068]: info: Initializing BBC Radios May 26 15:05:09 studio volumio[1068]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory May 26 15:05:09 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:05:09 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 15:05:09 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:05:09 studio volumio[1068]: info: Volumio Calling Home May 26 15:05:10 studio sudo[1455]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 26 15:05:10 studio sudo[1455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 15:05:10 studio sudo[1497]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 26 15:05:10 studio sudo[1455]: pam_unix(sudo:session): session closed for user root May 26 15:05:11 studio volumio5-onboarding[1453]: time=2026-05-26T15:05:11.402+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z May 26 15:05:12 studio volumio[1068]: info: Discovery: this is already registered, 3b577b02-4909-44b6-a3de-bfe26ca16002 May 26 15:05:12 studio volumio[1068]: info: Discovery: Found device Studio May 26 15:05:12 studio volumio[1068]: info: CoreCommandRouter::volumioGetState May 26 15:05:12 studio volumio[1068]: info: CorePlayQueue::getTrack 0 May 26 15:05:12 studio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. May 26 15:05:12 studio sudo[1502]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 26 15:05:12 studio sudo[1502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:05:12 studio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 26 15:05:12 studio volumio[1068]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.16 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 4 May 26 15:05:12 studio sudo[1505]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 15:05:12 studio sudo[1505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:05:12 studio volumio[1068]: info: MPD Permissions set May 26 15:05:12 studio volumio[1068]: info: MPD Permissions set May 26 15:05:12 studio volumio[1068]: info: VolumeController:: Volume=100 Mute =false May 26 15:05:12 studio sudo[1505]: pam_unix(sudo:session): session closed for user root May 26 15:05:12 studio sudo[1502]: pam_unix(sudo:session): session closed for user root May 26 15:05:12 studio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 26 15:05:12 studio volumio[1068]: info: CoreStateMachine::pushState May 26 15:05:12 studio volumio[1068]: info: CorePlayQueue::getTrack 0 May 26 15:05:12 studio volumio[1068]: info: CoreCommandRouter::volumioPushState May 26 15:05:12 studio volumio[1068]: 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: 5 May 26 15:05:13 studio volumio[1378]: Starting albumart workers May 26 15:05:13 studio upmpdcli[1507]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.0.10 port 49152 IPV6 port 49152 May 26 15:05:13 studio upmpdcli[1507]: :3:../src/mediaserver/cdplugins/plgwithslave.cxx:248::PlgWithSlave: starting streaming proxy on port 49149 May 26 15:05:13 studio volumio[1377]: Starting albumart workers May 26 15:05:13 studio volumio[1068]: 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: 5 May 26 15:05:13 studio volumio[1068]: info: Received Get System Info May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:05:13 studio volumio[1068]: info: Discovery: Getting this device information May 26 15:05:13 studio volumio-remote-updater[647]: [2026-05-26 15:05:13] [connect] Successful connection May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::volumioGetState May 26 15:05:13 studio volumio[1068]: info: CorePlayQueue::getTrack 0 May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:05:13 studio volumio5-onboarding[1453]: time=2026-05-26T15:05:13.539+02:00 level=INFO msg="system info for ae334301c8933e459f6ed362034b2e51" deviceName=Studio deviceVariant=volumio deviceModel= softwareVersion=4.119 May 26 15:05:13 studio volumio[1068]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 26 15:05:13 studio volumio5-onboarding[1453]: time=2026-05-26T15:05:13.616+02:00 level=INFO msg="bootstrapping state" hasInternet=true May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:13 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:14 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:05:14 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:05:15 studio volumio[1068]: info: Volumio called home May 26 15:05:15 studio volumio[1068]: info: Received Get System Info May 26 15:05:15 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:05:15 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:05:15 studio volumio[1068]: info: Discovery: Getting this device information May 26 15:05:15 studio volumio[1068]: info: CoreCommandRouter::volumioGetState May 26 15:05:15 studio volumio[1068]: info: CorePlayQueue::getTrack 0 May 26 15:05:15 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:05:15 studio volumio-remote-updater[647]: [2026-05-26 15:05:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1779800713 101 May 26 15:05:15 studio volumio[1068]: 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: 7 May 26 15:05:15 studio upmpdcli[1507]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout May 26 15:05:15 studio upmpdcli[1507]: :2:../src/main.cxx:732::MPD connection failed May 26 15:05:15 studio volumio[1379]: Starting albumart workers May 26 15:05:15 studio volumio-remote-updater[647]: Test mode disabled May 26 15:05:15 studio volumio-remote-updater[647]: Alpha mode disabled May 26 15:05:15 studio volumio-remote-updater[647]: Alpha legacy test mode disabled May 26 15:05:15 studio volumio[1068]: info: Starting Shairport Sync May 26 15:05:15 studio volumio[1068]: info: Starting Shairport Sync May 26 15:05:15 studio sudo[1540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 15:05:15 studio sudo[1540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:05:15 studio sudo[1538]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 26 15:05:15 studio sudo[1538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:05:15 studio volumio[1068]: info: Starting Shairport Sync May 26 15:05:15 studio sudo[1545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 15:05:15 studio sudo[1547]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 15:05:15 studio sudo[1547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:05:15 studio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 15:05:15 studio sudo[1545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:05:15 studio systemd[1]: shairport-sync.service: Deactivated successfully. May 26 15:05:15 studio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 15:05:15 studio systemd[1]: shairport-sync.service: Consumed 1.479s CPU time. May 26 15:05:15 studio volumio[1068]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 26 15:05:15 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 26 15:05:15 studio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 15:05:15 studio sudo[1540]: pam_unix(sudo:session): session closed for user root May 26 15:05:15 studio sudo[1547]: pam_unix(sudo:session): session closed for user root May 26 15:05:16 studio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 15:05:16 studio systemd[1]: shairport-sync.service: Deactivated successfully. May 26 15:05:16 studio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 15:05:16 studio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 15:05:16 studio sudo[1538]: pam_unix(sudo:session): session closed for user root May 26 15:05:16 studio sudo[1545]: pam_unix(sudo:session): session closed for user root May 26 15:05:16 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 15:05:16 studio volumio5-onboarding[1453]: time=2026-05-26T15:05:16.425+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" May 26 15:05:16 studio volumio5-onboarding[1453]: time=2026-05-26T15:05:16.426+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" May 26 15:05:16 studio volumio5-onboarding[1453]: time=2026-05-26T15:05:16.426+02:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" May 26 15:05:16 studio volumio[1068]: info: Shairport-Sync Started May 26 15:05:16 studio volumio[1068]: Error adding Membership: Error: addMembership EINVAL May 26 15:05:16 studio volumio[1068]: info: Upmpdcli Daemon Started May 26 15:05:16 studio volumio[1068]: info: Shairport-Sync Started May 26 15:05:16 studio volumio[1068]: info: Shairport-Sync Started May 26 15:05:16 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 15:05:16 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 15:05:16 studio volumio[1068]: info: Received Get System Info May 26 15:05:16 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:05:16 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:05:16 studio volumio[1068]: info: Discovery: Getting this device information May 26 15:05:16 studio volumio[1068]: info: CoreCommandRouter::volumioGetState May 26 15:05:16 studio volumio[1068]: info: CorePlayQueue::getTrack 0 May 26 15:05:16 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:05:17 studio volumio[1068]: info: CoreCommandRouter::volumioGetState May 26 15:05:17 studio volumio[1068]: info: CorePlayQueue::getTrack 0 May 26 15:05:17 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 15:05:17 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 15:05:17 studio volumio5-onboarding[1453]: time=2026-05-26T15:05:17.068+02:00 level=INFO msg="enabling local network discovery" May 26 15:05:17 studio volumio5-onboarding[1453]: time=2026-05-26T15:05:17.185+02:00 level=INFO msg="enabling BLE discovery" May 26 15:05:17 studio volumio[1068]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.16 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 8 May 26 15:05:17 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 26 15:05:17 studio volumio[1068]: info: CoreCommandRouter::volumioGetVisibleSources May 26 15:05:17 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 15:05:17 studio volumio[1068]: info: CoreCommandRouter::volumioGetState May 26 15:05:17 studio volumio[1068]: info: CorePlayQueue::getTrack 0 May 26 15:05:17 studio volumio[1068]: info: CoreCommandRouter::volumioGetState May 26 15:05:17 studio volumio[1068]: info: CorePlayQueue::getTrack 0 May 26 15:05:17 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 26 15:05:17 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 26 15:05:17 studio volumio[1068]: info: Received Get System Info May 26 15:05:17 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:05:17 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:05:17 studio volumio[1068]: info: Discovery: Getting this device information May 26 15:05:17 studio volumio[1068]: info: CoreCommandRouter::volumioGetState May 26 15:05:17 studio volumio[1068]: info: CorePlayQueue::getTrack 0 May 26 15:05:17 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:05:17 studio volumio[1068]: info: CoreCommandRouter::volumioGetState May 26 15:05:17 studio volumio[1068]: info: CorePlayQueue::getTrack 0 May 26 15:05:18 studio volumio[1068]: info: Listing playlists May 26 15:05:18 studio volumio[1068]: info: CoreCommandRouter::volumioGetQueue May 26 15:05:18 studio volumio5-onboarding[1453]: time=2026-05-26T15:05:18.342+02:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 15:05:18 studio volumio[1068]: info: CoreStateMachine::getQueue May 26 15:05:18 studio volumio[1068]: info: CorePlayQueue::getQueue May 26 15:05:19 studio upmpdcli[1507]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout May 26 15:05:19 studio upmpdcli[1507]: :2:../src/main.cxx:732::MPD connection failed May 26 15:05:22 studio volumio[1068]: info: CoreCommandRouter::volumioGetState May 26 15:05:22 studio volumio[1068]: info: CorePlayQueue::getTrack 0 May 26 15:05:23 studio volumio[1068]: info: CoreCommandRouter::volumioPlay May 26 15:05:23 studio volumio[1068]: info: CoreStateMachine::play index undefined May 26 15:05:23 studio volumio[1068]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 15:05:23 studio volumio[1068]: info: CorePlayQueue::getTrack 0 May 26 15:05:23 studio volumio[1068]: info: CoreStateMachine::startPlaybackTimer May 26 15:05:23 studio volumio[1068]: info: CorePlayQueue::getTrack 0 May 26 15:05:23 studio volumio[1068]: info: [1779800723677] ControllerWebradio::clearAddPlayTrack May 26 15:05:23 studio volumio[1068]: verbose: ControllerMpd::sendMpdCommand stop May 26 15:05:25 studio upmpdcli[1507]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout May 26 15:05:25 studio upmpdcli[1507]: :2:../src/main.cxx:732::MPD connection failed May 26 15:05:31 studio volumio[1068]: info: CoreCommandRouter::volumioPlay May 26 15:05:31 studio volumio[1068]: info: CoreStateMachine::play index undefined May 26 15:05:31 studio volumio[1068]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 15:05:31 studio volumio[1068]: info: CorePlayQueue::getTrack 0 May 26 15:05:31 studio volumio[1068]: info: CoreStateMachine::startPlaybackTimer May 26 15:05:31 studio volumio[1068]: info: CorePlayQueue::getTrack 0 May 26 15:05:31 studio volumio[1068]: info: [1779800731745] ControllerWebradio::clearAddPlayTrack May 26 15:05:31 studio volumio[1068]: verbose: ControllerMpd::sendMpdCommand stop May 26 15:05:35 studio upmpdcli[1507]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout May 26 15:05:35 studio upmpdcli[1507]: :2:../src/main.cxx:732::MPD connection failed May 26 15:05:38 studio mpd[1498]: 2026-05-26T15:05:37 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 26 15:05:39 studio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 26 15:05:39 studio volumio[1068]: info: CURURI: albums:// May 26 15:05:39 studio volumio[1068]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 15:05:39 studio volumio[1068]: TypeError: Cannot read properties of undefined (reading 'sendCommand') May 26 15:05:39 studio volumio[1068]: at /volumio/app/plugins/music_service/mpd/index.js:3000:22 May 26 15:05:39 studio volumio[1068]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) May 26 15:05:39 studio volumio[1068]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 15:05:40 studio systemd[1]: Started mpd.service - Music Player Daemon. May 26 15:05:41 studio sudo[1443]: pam_unix(sudo:session): session closed for user root May 26 15:05:41 studio sudo[1432]: pam_unix(sudo:session): session closed for user root May 26 15:05:49 studio sudo[1655]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-26 15:04' May 26 15:05:49 studio sudo[1655]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"