Jun 19 09:41:00 volumio2 sudo[1182]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jun 19 09:41:00 volumio2 nmbd[1174]: [2025/06/19 09:41:00.021184, 0] ../../source3/nmbd/nmbd.c:901(main) Jun 19 09:41:00 volumio2 nmbd[1174]: nmbd version 4.17.12-Debian started. Jun 19 09:41:00 volumio2 nmbd[1174]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jun 19 09:41:00 volumio2 bluetoothd[799]: Battery Provider Manager created Jun 19 09:41:00 volumio2 kernel: Bluetooth: MGMT ver 1.23 Jun 19 09:41:00 volumio2 sudo[1182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 19 09:41:00 volumio2 nmbd[1174]: [2025/06/19 09:41:00.025013, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Jun 19 09:41:00 volumio2 nmbd[1174]: started asyncdns process 1184 Jun 19 09:41:00 volumio2 nmbd[1174]: [2025/06/19 09:41:00.025823, 0] ../../lib/util/become_daemon.c:150(daemon_status) Jun 19 09:41:00 volumio2 nmbd[1174]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jun 19 09:41:00 volumio2 nmbd[1174]: [2025/06/19 09:41:00.025868, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Jun 19 09:41:00 volumio2 nmbd[1174]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jun 19 09:41:00 volumio2 sudo[1182]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:00 volumio2 kernel: NET: Registered PF_ALG protocol family Jun 19 09:41:00 volumio2 sudo[1186]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jun 19 09:41:00 volumio2 sudo[1186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 19 09:41:00 volumio2 bluetoothd[799]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled Jun 19 09:41:00 volumio2 bluetoothd[799]: bap: Operation not supported (95) Jun 19 09:41:00 volumio2 bluealsa[934]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0, ...) Jun 19 09:41:00 volumio2 bluetoothd[799]: Adv Monitor Manager created with supported features:0x00000000, enabled features:0x00000000, max number of supported monitors:32, max number of supported patterns:16 Jun 19 09:41:00 volumio2 bluealsa[934]: bluez.c:153: Registering media application: /org/bluez/hci0 Jun 19 09:41:00 volumio2 bluealsa[934]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 Jun 19 09:41:00 volumio2 bluealsa[934]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 Jun 19 09:41:00 volumio2 bluealsa[934]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 Jun 19 09:41:00 volumio2 bluealsa[934]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 Jun 19 09:41:00 volumio2 bluealsa[934]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0, ...) Jun 19 09:41:00 volumio2 bluetoothd[799]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1 Jun 19 09:41:00 volumio2 bluetoothd[799]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2 Jun 19 09:41:00 volumio2 bluetoothd[799]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2 Jun 19 09:41:00 volumio2 bluetoothd[799]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1 Jun 19 09:41:00 volumio2 sudo[1186]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:00 volumio2 sudo[1201]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 19 09:41:00 volumio2 sudo[1201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:00 volumio2 sudo[1201]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:00 volumio2 sudo[1203]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 19 09:41:00 volumio2 sudo[1203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:00 volumio2 sudo[1203]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:00 volumio2 sudo[1211]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 19 09:41:00 volumio2 sudo[1211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:00 volumio2 sudo[1211]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:00 volumio2 sudo[1213]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 19 09:41:00 volumio2 sudo[1213]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:00 volumio2 avahi-daemon[797]: Service "Volumio2" (/services/volumio.service) successfully established. Jun 19 09:41:00 volumio2 ntpd[1102]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 19 09:41:00 volumio2 ntpd[1102]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jun 19 09:41:00 volumio2 ntpd[1102]: DNS: dns_check: DNS error: -11, System error Jun 19 09:41:00 volumio2 ntpd[1102]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Jun 19 09:41:01 volumio2 ntpd[1102]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 19 09:41:01 volumio2 ntpd[1102]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jun 19 09:41:01 volumio2 ntpd[1102]: DNS: dns_check: DNS error: -11, System error Jun 19 09:41:01 volumio2 ntpd[1102]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Jun 19 09:41:02 volumio2 ntpd[1102]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 19 09:41:02 volumio2 ntpd[1102]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jun 19 09:41:02 volumio2 ntpd[1102]: DNS: dns_check: DNS error: -11, System error Jun 19 09:41:02 volumio2 ntpd[1102]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Jun 19 09:41:03 volumio2 ntpd[1102]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 19 09:41:03 volumio2 ntpd[1102]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jun 19 09:41:03 volumio2 ntpd[1102]: DNS: dns_check: DNS error: -11, System error Jun 19 09:41:03 volumio2 ntpd[1102]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Jun 19 09:41:03 volumio2 systemd[1]: systemd-rfkill.service: Deactivated successfully. Jun 19 09:41:03 volumio2 sudo[1213]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:03 volumio2 sudo[1223]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 19 09:41:03 volumio2 sudo[1223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:03 volumio2 sudo[1223]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:03 volumio2 sudo[1225]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Jun 19 09:41:03 volumio2 sudo[1225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:03 volumio2 sudo[1225]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:03 volumio2 sudo[1233]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jun 19 09:41:03 volumio2 sudo[1233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 19 09:41:03 volumio2 sudo[1233]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:03 volumio2 sudo[1235]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jun 19 09:41:03 volumio2 sudo[1235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 19 09:41:03 volumio2 sudo[1235]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:03 volumio2 wpa_supplicant[1240]: Successfully initialized wpa_supplicant Jun 19 09:41:03 volumio2 sudo[1241]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 19 09:41:03 volumio2 sudo[1241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 19 09:41:03 volumio2 dhcpcd[872]: ps_ctl_dispatch: cannot handle another client Jun 19 09:41:03 volumio2 dhcpcd[872]: control_free: No such file or directory Jun 19 09:41:03 volumio2 sudo[1241]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:04 volumio2 wpa_supplicant[1245]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=WORLD Jun 19 09:41:04 volumio2 volumio-remote-updater[811]: [2025-06-19 09:41:04] [info] asio async_connect error: asio.system:111 (Connection refused) Jun 19 09:41:04 volumio2 volumio-remote-updater[811]: [2025-06-19 09:41:04] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jun 19 09:41:04 volumio2 volumio-remote-updater[811]: [2025-06-19 09:41:04] [error] handle_connect error: Connection refused Jun 19 09:41:04 volumio2 sudo[1248]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 19 09:41:04 volumio2 sudo[1248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:04 volumio2 sudo[1248]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:05 volumio2 sudo[1252]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 19 09:41:05 volumio2 sudo[1252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:05 volumio2 sudo[1252]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:06 volumio2 sudo[1256]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 19 09:41:06 volumio2 sudo[1256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:06 volumio2 sudo[1256]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:07 volumio2 wpa_supplicant[1245]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=DE Jun 19 09:41:07 volumio2 wpa_supplicant[1245]: wlan0: SME: Trying to authenticate with f6:92:bf:b9:18:e7 (SSID='Dream Machine' freq=5220 MHz) Jun 19 09:41:07 volumio2 kernel: wlan0: authenticate with f6:92:bf:b9:18:e7 (local address=08:71:90:01:94:5b) Jun 19 09:41:07 volumio2 kernel: wlan0: send auth to f6:92:bf:b9:18:e7 (try 1/3) Jun 19 09:41:07 volumio2 wpa_supplicant[1245]: wlan0: Trying to associate with f6:92:bf:b9:18:e7 (SSID='Dream Machine' freq=5220 MHz) Jun 19 09:41:07 volumio2 kernel: wlan0: authenticated Jun 19 09:41:07 volumio2 kernel: wlan0: associate with f6:92:bf:b9:18:e7 (try 1/3) Jun 19 09:41:07 volumio2 kernel: wlan0: RX AssocResp from f6:92:bf:b9:18:e7 (capab=0x1111 status=0 aid=8) Jun 19 09:41:07 volumio2 wpa_supplicant[1245]: wlan0: Associated with f6:92:bf:b9:18:e7 Jun 19 09:41:07 volumio2 wpa_supplicant[1245]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 19 09:41:07 volumio2 kernel: wlan0: associated Jun 19 09:41:07 volumio2 wpa_supplicant[1245]: wlan0: WPA: Key negotiation completed with f6:92:bf:b9:18:e7 [PTK=CCMP GTK=CCMP] Jun 19 09:41:07 volumio2 wpa_supplicant[1245]: wlan0: CTRL-EVENT-CONNECTED - Connection to f6:92:bf:b9:18:e7 completed [id=0 id_str=] Jun 19 09:41:07 volumio2 dhcpcd[872]: wlan0: carrier acquired Jun 19 09:41:07 volumio2 dhcpcd[872]: wlan0: connected to Access Point: Dream Machine Jun 19 09:41:07 volumio2 dhcpcd[872]: wlan0: IAID 90:01:94:5b Jun 19 09:41:07 volumio2 dhcpcd[872]: wlan0: soliciting an IPv6 router Jun 19 09:41:07 volumio2 dhcpcd[872]: wlan0: rebinding lease of 192.168.1.215 Jun 19 09:41:07 volumio2 sudo[1278]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 19 09:41:07 volumio2 sudo[1278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:07 volumio2 sudo[1278]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:07 volumio2 dhcpcd[872]: wlan0: probing address 192.168.1.215/24 Jun 19 09:41:08 volumio2 sudo[1282]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 19 09:41:08 volumio2 sudo[1282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:08 volumio2 sudo[1282]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:09 volumio2 sudo[1286]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 19 09:41:09 volumio2 sudo[1286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:09 volumio2 sudo[1286]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:10 volumio2 kernel: platform USBC000:00: deferred probe pending: (reason unknown) Jun 19 09:41:10 volumio2 sudo[1290]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 19 09:41:10 volumio2 sudo[1290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:10 volumio2 sudo[1290]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:11 volumio2 volumio-remote-updater[811]: [2025-06-19 09:41:11] [info] asio async_connect error: asio.system:111 (Connection refused) Jun 19 09:41:11 volumio2 volumio-remote-updater[811]: [2025-06-19 09:41:11] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jun 19 09:41:11 volumio2 volumio-remote-updater[811]: [2025-06-19 09:41:11] [error] handle_connect error: Connection refused Jun 19 09:41:11 volumio2 sudo[1294]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 19 09:41:11 volumio2 sudo[1294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:11 volumio2 sudo[1294]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:12 volumio2 sudo[1298]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 19 09:41:12 volumio2 sudo[1298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:12 volumio2 sudo[1298]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:13 volumio2 dhcpcd[872]: wlan0: leased 192.168.1.215 for 86400 seconds Jun 19 09:41:13 volumio2 avahi-daemon[797]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.215. Jun 19 09:41:13 volumio2 dhcpcd[872]: wlan0: adding route to 192.168.1.0/24 Jun 19 09:41:13 volumio2 avahi-daemon[797]: New relevant interface wlan0.IPv4 for mDNS. Jun 19 09:41:13 volumio2 dhcpcd[872]: wlan0: adding default route via 192.168.1.1 Jun 19 09:41:13 volumio2 avahi-daemon[797]: Registering new address record for 192.168.1.215 on wlan0.IPv4. Jun 19 09:41:13 volumio2 systemd[1]: welcome.service: Deactivated successfully. Jun 19 09:41:13 volumio2 systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 19 09:41:13 volumio2 systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 19 09:41:13 volumio2 systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 19 09:41:13 volumio2 welcome[1314]: Resolved ip:[1] 192.168.1.215 Jun 19 09:41:13 volumio2 systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 19 09:41:13 volumio2 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 19 09:41:13 volumio2 systemd[1]: Started nmbd.service - Samba NMB Daemon. Jun 19 09:41:13 volumio2 nmbd[1174]: [2025/06/19 09:41:13.329756, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 19 09:41:13 volumio2 nmbd[1174]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.215 for name WORKGROUP<1d>. Jun 19 09:41:13 volumio2 nmbd[1174]: This response was from IP 192.168.1.38, reporting an IP address of 192.168.1.38. Jun 19 09:41:13 volumio2 systemd[1]: Starting winbind.service - Samba Winbind Daemon... Jun 19 09:41:13 volumio2 winbindd[1329]: [2025/06/19 09:41:13.540486, 0] ../../source3/winbindd/winbindd.c:1440(main) Jun 19 09:41:13 volumio2 winbindd[1329]: winbindd version 4.17.12-Debian started. Jun 19 09:41:13 volumio2 winbindd[1329]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jun 19 09:41:13 volumio2 winbindd[1329]: [2025/06/19 09:41:13.546267, 0] ../../source3/winbindd/winbindd_cache.c:3117(initialize_winbindd_cache) Jun 19 09:41:13 volumio2 winbindd[1329]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 19 09:41:13 volumio2 systemd[1]: Started winbind.service - Samba Winbind Daemon. Jun 19 09:41:13 volumio2 systemd[1]: Starting smbd.service - Samba SMB Daemon... Jun 19 09:41:13 volumio2 smbd[1345]: [2025/06/19 09:41:13.810843, 0] ../../source3/smbd/server.c:1741(main) Jun 19 09:41:13 volumio2 smbd[1345]: smbd version 4.17.12-Debian started. Jun 19 09:41:13 volumio2 smbd[1345]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jun 19 09:41:13 volumio2 sudo[1349]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 19 09:41:13 volumio2 sudo[1349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:13 volumio2 sudo[1349]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:13 volumio2 sudo[1354]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 19 09:41:13 volumio2 sudo[1354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 19 09:41:13 volumio2 sudo[1354]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:13 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 19 09:41:13 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 19 09:41:13 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 19 09:41:13 volumio2 systemd[1]: shairport-sync.service: Consumed 1.786s CPU time. Jun 19 09:41:13 volumio2 avahi-daemon[797]: Got SIGTERM, quitting. Jun 19 09:41:13 volumio2 systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jun 19 09:41:13 volumio2 avahi-daemon[797]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.215. Jun 19 09:41:13 volumio2 avahi-daemon[797]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jun 19 09:41:13 volumio2 avahi-daemon[797]: avahi-daemon 0.8 exiting. Jun 19 09:41:13 volumio2 systemd[1]: avahi-daemon.service: Deactivated successfully. Jun 19 09:41:13 volumio2 systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jun 19 09:41:13 volumio2 systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jun 19 09:41:13 volumio2 avahi-daemon[1360]: Process 797 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Jun 19 09:41:13 volumio2 avahi-daemon[1360]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Jun 19 09:41:13 volumio2 avahi-daemon[1360]: Successfully dropped root privileges. Jun 19 09:41:13 volumio2 avahi-daemon[1360]: avahi-daemon 0.8 starting up. Jun 19 09:41:13 volumio2 systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jun 19 09:41:13 volumio2 avahi-daemon[1360]: Successfully called chroot(). Jun 19 09:41:13 volumio2 avahi-daemon[1360]: Successfully dropped remaining capabilities. Jun 19 09:41:13 volumio2 avahi-daemon[1360]: Loading service file /services/volumio.service. Jun 19 09:41:13 volumio2 avahi-daemon[1360]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.215. Jun 19 09:41:13 volumio2 avahi-daemon[1360]: New relevant interface wlan0.IPv4 for mDNS. Jun 19 09:41:13 volumio2 avahi-daemon[1360]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jun 19 09:41:13 volumio2 avahi-daemon[1360]: New relevant interface lo.IPv4 for mDNS. Jun 19 09:41:13 volumio2 avahi-daemon[1360]: Network interface enumeration completed. Jun 19 09:41:13 volumio2 avahi-daemon[1360]: Registering new address record for 192.168.1.215 on wlan0.IPv4. Jun 19 09:41:13 volumio2 avahi-daemon[1360]: Registering new address record for 127.0.0.1 on lo.IPv4. Jun 19 09:41:13 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 19 09:41:13 volumio2 systemd[1]: Started wireless.service - Wireless Services. Jun 19 09:41:13 volumio2 systemd[1]: Started volumio.service - Volumio Backend Module. Jun 19 09:41:13 volumio2 systemd[1]: Started screenshot.service - Process screenshots triggered by PrtSc-button. Jun 19 09:41:13 volumio2 systemd[1]: Started soundcard-init.service - Intel SST and HDA soundcard init service. Jun 19 09:41:13 volumio2 systemd[1]: Started volumio-kiosk.service - Start Volumio Kiosk. Jun 19 09:41:13 volumio2 systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Jun 19 09:41:14 volumio2 systemd[1]: Started smbd.service - Samba SMB Daemon. Jun 19 09:41:14 volumio2 systemd[1]: Reached target multi-user.target - Multi-User System. Jun 19 09:41:14 volumio2 systemd[1]: Reached target graphical.target - Graphical Interface. Jun 19 09:41:14 volumio2 volumio-cpu-tweak[1388]: Setting RT Priority for mpd Jun 19 09:41:14 volumio2 systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Jun 19 09:41:14 volumio2 volumio-cpu-tweak[1428]: pid 35's current scheduling policy: SCHED_OTHER Jun 19 09:41:14 volumio2 volumio-cpu-tweak[1428]: pid 35's current scheduling priority: 0 Jun 19 09:41:14 volumio2 volumio-cpu-tweak[1388]: Setting MPD Affinity Jun 19 09:41:14 volumio2 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jun 19 09:41:14 volumio2 systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Jun 19 09:41:14 volumio2 volumio-cpu-tweak[1435]: pid 3's current affinity mask: f Jun 19 09:41:14 volumio2 volumio-cpu-tweak[1388]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jun 19 09:41:14 volumio2 systemd[1]: Startup finished in 9.526s (firmware) + 2.499s (loader) + 8.522s (kernel) + 17.293s (userspace) = 37.842s. Jun 19 09:41:14 volumio2 systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Jun 19 09:41:14 volumio2 startx[1455]: X.Org X Server 1.21.1.7 Jun 19 09:41:14 volumio2 startx[1455]: X Protocol Version 11, Revision 0 Jun 19 09:41:14 volumio2 startx[1455]: Current Operating System: Linux volumio2 6.12.30-volumio #2 SMP PREEMPT_DYNAMIC Wed May 28 19:37:30 BST 2025 x86_64 Jun 19 09:41:14 volumio2 startx[1455]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=af40ded5-e8bf-4b66-9e2a-8aeccf2c621a bootpart=UUID=325D-29E8 datapart=UUID=7057e3d4-a977-4ed5-aa15-7c338778d338 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no Jun 19 09:41:14 volumio2 startx[1455]: xorg-server 2:21.1.7-3+deb12u9 (https://www.debian.org/support) Jun 19 09:41:14 volumio2 startx[1455]: Current version of pixman: 0.42.2 Jun 19 09:41:14 volumio2 startx[1455]: Before reporting problems, check http://wiki.x.org Jun 19 09:41:14 volumio2 startx[1455]: to make sure that you have the latest version. Jun 19 09:41:14 volumio2 startx[1455]: Markers: (--) probed, (**) from config file, (==) default setting, Jun 19 09:41:14 volumio2 startx[1455]: (++) from command line, (!!) notice, (II) informational, Jun 19 09:41:14 volumio2 startx[1455]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jun 19 09:41:14 volumio2 startx[1455]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Jun 19 09:41:14 2025 Jun 19 09:41:14 volumio2 startx[1455]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jun 19 09:41:14 volumio2 soundcard-init.sh[1475]: Simple mixer control 'Master',0 Jun 19 09:41:14 volumio2 soundcard-init.sh[1475]: Capabilities: pvolume pvolume-joined pswitch pswitch-joined Jun 19 09:41:14 volumio2 soundcard-init.sh[1475]: Playback channels: Mono Jun 19 09:41:14 volumio2 soundcard-init.sh[1475]: Limits: Playback 0 - 87 Jun 19 09:41:14 volumio2 soundcard-init.sh[1475]: Mono: Playback 65 [75%] [-16.50dB] [on] Jun 19 09:41:14 volumio2 systemd[1]: soundcard-init.service: Deactivated successfully. Jun 19 09:41:14 volumio2 ntpd[1102]: IO: Listen normally on 3 wlan0 192.168.1.215:123 Jun 19 09:41:14 volumio2 ntpd[1102]: IO: new interface(s) found: waking up resolver Jun 19 09:41:14 volumio2 ntpd[1102]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 19 09:41:14 volumio2 ntpd[1102]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jun 19 09:41:14 volumio2 ntpd[1102]: DNS: Pool taking: 79.133.44.137 Jun 19 09:41:14 volumio2 ntpd[1102]: DNS: Pool taking: 192.248.187.154 Jun 19 09:41:14 volumio2 ntpd[1102]: DNS: Pool taking: 85.220.190.246 Jun 19 09:41:14 volumio2 ntpd[1102]: DNS: Pool taking: 141.144.246.224 Jun 19 09:41:14 volumio2 ntpd[1102]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Jun 19 09:41:14 volumio2 avahi-daemon[1360]: Server startup complete. Host name is volumio2.local. Local service cookie is 3836144352. Jun 19 09:41:15 volumio2 volumio[1366]: info: ------------------------------------------- Jun 19 09:41:15 volumio2 volumio[1366]: info: ----- Volumio3 ---- Jun 19 09:41:15 volumio2 volumio[1366]: info: ------------------------------------------- Jun 19 09:41:15 volumio2 volumio[1366]: info: ----- System startup ---- Jun 19 09:41:15 volumio2 volumio[1366]: info: ------------------------------------------- Jun 19 09:41:15 volumio2 ntpd[1102]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 19 09:41:15 volumio2 ntpd[1102]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jun 19 09:41:15 volumio2 ntpd[1102]: DNS: Pool skipping: 192.248.187.154 Jun 19 09:41:15 volumio2 ntpd[1102]: DNS: Pool taking: 3.121.254.221 Jun 19 09:41:15 volumio2 ntpd[1102]: DNS: Pool taking: 213.239.234.28 Jun 19 09:41:15 volumio2 ntpd[1102]: DNS: Pool taking: 152.70.19.169 Jun 19 09:41:15 volumio2 ntpd[1102]: DNS: Pool taking: 2a09:e1c1:efc2:1337::123 Jun 19 09:41:15 volumio2 ntpd[1102]: DNS: Pool taking: 2003:a:87f:c37c::3 Jun 19 09:41:15 volumio2 ntpd[1102]: DNS: Pool taking: 2a01:239:25e:bd00::2 Jun 19 09:41:15 volumio2 ntpd[1102]: DNS: Pool taking: 2a13:5681:1:11:: Jun 19 09:41:15 volumio2 ntpd[1102]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Jun 19 09:41:15 volumio2 avahi-daemon[1360]: Service "Volumio2" (/services/volumio.service) successfully established. Jun 19 09:41:15 volumio2 volumio[1366]: info: MYVOLUMIO Environment detected Jun 19 09:41:15 volumio2 volumio[1366]: info: Plugin folders cleanup Jun 19 09:41:16 volumio2 volumio[1366]: info: Scanning into folder /volumio/app/plugins/ Jun 19 09:41:16 volumio2 volumio[1366]: info: Scanning category audio_interface Jun 19 09:41:16 volumio2 volumio[1366]: info: Scanning category miscellanea Jun 19 09:41:16 volumio2 volumio[1366]: info: Scanning category music_service Jun 19 09:41:16 volumio2 volumio[1366]: info: Scanning category plugins.json Jun 19 09:41:16 volumio2 volumio[1366]: info: Scanning category system_controller Jun 19 09:41:16 volumio2 volumio[1366]: info: Scanning category user_interface Jun 19 09:41:16 volumio2 volumio[1366]: info: Scanning into folder /data/plugins/ Jun 19 09:41:16 volumio2 volumio[1366]: info: Scanning category audio_interface Jun 19 09:41:16 volumio2 volumio[1366]: info: Scanning category music_service Jun 19 09:41:16 volumio2 volumio[1366]: info: Scanning category user_interface Jun 19 09:41:16 volumio2 volumio[1366]: info: Plugin folders cleanup completed Jun 19 09:41:16 volumio2 volumio[1366]: info: ------------------------------------------- Jun 19 09:41:16 volumio2 volumio[1366]: info: ----- Core plugins startup ---- Jun 19 09:41:16 volumio2 volumio[1366]: info: ------------------------------------------- Jun 19 09:41:16 volumio2 volumio[1366]: info: Loading plugins from folder /volumio/app/plugins/ Jun 19 09:41:16 volumio2 volumio[1366]: info: Adding plugin upnp to MyMusic Plugins Jun 19 09:41:16 volumio2 volumio[1366]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 19 09:41:16 volumio2 volumio[1366]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 19 09:41:16 volumio2 volumio[1366]: info: Loading plugins from folder /data/plugins/ Jun 19 09:41:16 volumio2 volumio[1366]: info: Loading plugin "system"... Jun 19 09:41:16 volumio2 volumio[1366]: info: Loading plugin "appearance"... Jun 19 09:41:16 volumio2 ntpd[1102]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 19 09:41:16 volumio2 ntpd[1102]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jun 19 09:41:16 volumio2 ntpd[1102]: DNS: Pool taking: 129.70.132.36 Jun 19 09:41:16 volumio2 ntpd[1102]: DNS: Pool taking: 194.164.164.175 Jun 19 09:41:16 volumio2 ntpd[1102]: DNS: Pool taking: 162.159.200.1 Jun 19 09:41:16 volumio2 ntpd[1102]: DNS: Pool taking: 131.234.220.231 Jun 19 09:41:16 volumio2 ntpd[1102]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Jun 19 09:41:17 volumio2 volumio[1366]: info: Loading plugin "network"... Jun 19 09:41:17 volumio2 volumio[1366]: info: Refreshing Cached IP Addresses Jun 19 09:41:17 volumio2 sudo[1610]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 19 09:41:17 volumio2 sudo[1610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:17 volumio2 sudo[1612]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 19 09:41:17 volumio2 sudo[1612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:17 volumio2 sudo[1610]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:17 volumio2 sudo[1612]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:17 volumio2 volumio[1366]: info: Loading plugin "services"... Jun 19 09:41:17 volumio2 volumio[1366]: info: Loading plugin "alsa_controller"... Jun 19 09:41:17 volumio2 sudo[1621]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 19 09:41:17 volumio2 sudo[1621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:17 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 09:41:17 volumio2 volumio[1366]: info: Loading plugin "wizard"... Jun 19 09:41:17 volumio2 volumio[1366]: info: Loading plugin "networkfs"... Jun 19 09:41:17 volumio2 volumio[1366]: info: Starting Udev Watcher for removable devices Jun 19 09:41:17 volumio2 volumio[1366]: info: Ignoring mount for partition: boot Jun 19 09:41:17 volumio2 volumio[1366]: info: Ignoring mount for partition: volumio Jun 19 09:41:17 volumio2 volumio[1366]: info: Ignoring mount for partition: volumio_data Jun 19 09:41:17 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 19 09:41:17 volumio2 volumio[1366]: info: Loading plugin "volumio_command_line_client"... Jun 19 09:41:17 volumio2 volumio[1366]: info: Loading plugin "upnp"... Jun 19 09:41:17 volumio2 volumio[1366]: info: [1750318877567] Starting Upmpd Daemon Jun 19 09:41:17 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 19 09:41:17 volumio2 volumio[1366]: info: Loading plugin "my_music"... Jun 19 09:41:17 volumio2 ntpd[1102]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 19 09:41:17 volumio2 volumio[1366]: info: Loading plugin "mpd"... Jun 19 09:41:17 volumio2 ntpd[1102]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jun 19 09:41:17 volumio2 ntpd[1102]: DNS: Pool taking: 194.50.19.204 Jun 19 09:41:17 volumio2 ntpd[1102]: DNS: Pool taking: 213.209.109.44 Jun 19 09:41:17 volumio2 ntpd[1102]: DNS: Pool taking: 217.79.189.239 Jun 19 09:41:17 volumio2 ntpd[1102]: DNS: Pool taking: 194.59.205.229 Jun 19 09:41:17 volumio2 ntpd[1102]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Jun 19 09:41:17 volumio2 volumio[1366]: info: Loading plugin "upnp_browser"... Jun 19 09:41:18 volumio2 volumio[1366]: info: Loading plugin "alarm-clock"... Jun 19 09:41:18 volumio2 volumio[1366]: info: Loading plugin "airplay_emulation"... Jun 19 09:41:18 volumio2 volumio[1366]: info: Starting Shairport Sync Jun 19 09:41:18 volumio2 volumio[1366]: info: Loading plugin "last_100"... Jun 19 09:41:18 volumio2 volumio[1366]: info: Loading plugin "webradio"... Jun 19 09:41:18 volumio2 volumio[1366]: info: Loading plugin "i2s_dacs"... Jun 19 09:41:18 volumio2 volumio[1366]: info: I2S DAC not set, start Auto-detection Jun 19 09:41:18 volumio2 volumio[1366]: info: Loading plugin "volumiodiscovery"... Jun 19 09:41:18 volumio2 volumio[1366]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 19 09:41:18 volumio2 volumio[1366]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 19 09:41:18 volumio2 volumio[1366]: *** WARNING *** For more information see Jun 19 09:41:18 volumio2 volumio[1366]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 19 09:41:18 volumio2 volumio[1366]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 19 09:41:18 volumio2 node[1366]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 19 09:41:18 volumio2 volumio[1366]: *** WARNING *** For more information see Jun 19 09:41:18 volumio2 node[1366]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 19 09:41:18 volumio2 node[1366]: *** WARNING *** For more information see Jun 19 09:41:18 volumio2 node[1366]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 19 09:41:18 volumio2 node[1366]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 19 09:41:18 volumio2 node[1366]: *** WARNING *** For more information see Jun 19 09:41:18 volumio2 volumio[1366]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 19 09:41:18 volumio2 volumio[1366]: info: Discovery: Started advertising with name: Volumio2 Jun 19 09:41:18 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 19 09:41:18 volumio2 volumio[1366]: info: Loading plugin "spop"... Jun 19 09:41:18 volumio2 sudo[1621]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:19 volumio2 volumio[1366]: info: Loading plugin "now_playing"... Jun 19 09:41:19 volumio2 volumio[1366]: info: Loading plugin "outputs"... Jun 19 09:41:19 volumio2 volumio[1366]: info: Loading plugin "albumart"... Jun 19 09:41:19 volumio2 volumio[1366]: info: Plugin example_plugin is not enabled Jun 19 09:41:19 volumio2 volumio[1366]: info: Loading plugin "inputs"... Jun 19 09:41:19 volumio2 volumio[1366]: info: Loading plugin "updater_comm"... Jun 19 09:41:19 volumio2 volumio[1764]: Forking 3 albumart workers Jun 19 09:41:19 volumio2 volumio[1366]: info: Plugin mpdemulation is not enabled Jun 19 09:41:19 volumio2 volumio[1366]: info: Loading plugin "rest_api"... Jun 19 09:41:19 volumio2 volumio[1366]: info: Loading plugin "websocket"... Jun 19 09:41:19 volumio2 volumio[1366]: info: Starting Socket.io Server version 1.7.4 Jun 19 09:41:19 volumio2 volumio[1366]: info: Loading plugin "fusiondsp"... Jun 19 09:41:20 volumio2 volumio[1780]: Starting albumart workers Jun 19 09:41:20 volumio2 volumio[1781]: Starting albumart workers Jun 19 09:41:20 volumio2 volumio[1366]: info: Applying required configuration parameters for plugin fusiondsp Jun 19 09:41:20 volumio2 volumio[1783]: Starting albumart workers Jun 19 09:41:20 volumio2 volumio[1366]: info: Loading i18n strings for locale de Jun 19 09:41:20 volumio2 volumio[1366]: Updating browse sources language Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::initPlayerControls Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: Express server listening on port 3000 Jun 19 09:41:20 volumio2 volumio[1366]: [Metrics] WebUI: 5s 193.76ms Jun 19 09:41:20 volumio2 volumio[1366]: info: Setting Device type: x86 Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreStateMachine::resetVolumioState Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreStateMachine::getcurrentVolume Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::volumioRetrievevolume Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreStateMachine::pushState Jun 19 09:41:20 volumio2 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 19 09:41:20 volumio2 volumio[1366]: info: Completed loading Core Plugins Jun 19 09:41:20 volumio2 volumio[1366]: info: Preparing to generate the ALSA configuration file Jun 19 09:41:20 volumio2 volumio[1366]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 19 09:41:20 volumio2 volumio[1366]: info: Reading ALSA contributions from plugins. Jun 19 09:41:20 volumio2 volumio[1366]: info: Volumio Network Manager: Network status updated: 2 Jun 19 09:41:20 volumio2 volumio[1366]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 19 09:41:20 volumio2 volumio[1366]: info: Reloading queue from file Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreStateMachine::setRepeat null single undefined Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreStateMachine::pushState Jun 19 09:41:20 volumio2 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreStateMachine::setRandom null Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreStateMachine::pushState Jun 19 09:41:20 volumio2 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 19 09:41:20 volumio2 volumio[1366]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.11 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 19 09:41:20 volumio2 volumio[1366]: info: Discovery: adding 6b56f2d5-cbd9-4eb4-af79-a9ffa3dae30f Jun 19 09:41:20 volumio2 volumio[1366]: info: Discovery: Found device Volumio2 Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:41:20 volumio2 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 19 09:41:20 volumio2 volumio[1366]: info: Discovery: this is already registered, 6b56f2d5-cbd9-4eb4-af79-a9ffa3dae30f Jun 19 09:41:20 volumio2 volumio[1366]: info: Discovery: Found device Volumio2 Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:41:20 volumio2 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 19 09:41:20 volumio2 volumio[1366]: info: Asound.conf file written Jun 19 09:41:20 volumio2 sudo[1853]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 19 09:41:20 volumio2 sudo[1853]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:20 volumio2 sudo[1853]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:20 volumio2 volumio[1366]: info: Output device has changed, restarting MPD Jun 19 09:41:20 volumio2 volumio[1366]: info: Output device has changed, restarting Shairport Sync Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:20 volumio2 sudo[1862]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 19 09:41:20 volumio2 sudo[1862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:20 volumio2 sudo[1860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 19 09:41:20 volumio2 sudo[1860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:20 volumio2 volumio[1366]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 19 09:41:20 volumio2 volumio[1366]: info: ___________ START PLUGINS ___________ Jun 19 09:41:20 volumio2 sudo[1860]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:20 volumio2 volumio[1366]: info: ControllerMpd::onStart: Initializing MPD Jun 19 09:41:20 volumio2 volumio[1366]: info: Creating MPD Configuration file Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 09:41:20 volumio2 volumio[1366]: info: [1750318880537] CoreMusicLibrary::Adding element Medienserver Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 09:41:20 volumio2 sudo[1871]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 19 09:41:20 volumio2 sudo[1871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:20 volumio2 sudo[1871]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:20 volumio2 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 19 09:41:20 volumio2 sudo[1873]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 19 09:41:20 volumio2 sudo[1873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:20 volumio2 setdatetime-helper.sh[1869]: Time is already synchronized. Jun 19 09:41:20 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 19 09:41:20 volumio2 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 19 09:41:20 volumio2 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 19 09:41:20 volumio2 volumio[1366]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 19 09:41:20 volumio2 ntpd[1102]: CLOCK: time stepped by -0.133147 Jun 19 09:41:20 volumio2 ntpd[1102]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:20 volumio2 systemd-journald[515]: Time jumped backwards, rotating. Jun 19 09:41:20 volumio2 systemd[1]: mpd.service: Deactivated successfully. Jun 19 09:41:20 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 19 09:41:20 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Jun 19 09:41:20 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 19 09:41:20 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 19 09:41:20 volumio2 volumio[1366]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 09:41:20 volumio2 volumio[1366]: info: [1750318880464] CoreMusicLibrary::Adding element Last_100 Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 09:41:20 volumio2 volumio[1366]: info: [1750318880465] CoreMusicLibrary::Adding element Webradio Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 09:41:20 volumio2 volumio[1366]: info: Initializing BBC Radios Jun 19 09:41:20 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 19 09:41:20 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: Creating Spotify config file Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: [now-playing] ConfigUpdater: config is up to date. Jun 19 09:41:20 volumio2 volumio[1366]: info: Loading i18n strings for locale de Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: FusionDsp - mixtype--------------------- None Jun 19 09:41:20 volumio2 volumio[1366]: info: Volumio Calling Home Jun 19 09:41:20 volumio2 sudo[1899]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 19 09:41:20 volumio2 sudo[1899]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 19 09:41:20 volumio2 sudo[1906]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 19 09:41:20 volumio2 sudo[1899]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:20 volumio2 volumio[1366]: info: Preparing to generate the ALSA configuration file Jun 19 09:41:20 volumio2 volumio[1366]: info: [now-playing] App is listening on port 4004. Jun 19 09:41:20 volumio2 volumio[1366]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jun 19 09:41:20 volumio2 volumio[1366]: info: Discovery: adding 04720723-0b84-4186-8f09-f9ac9b9f00f9 Jun 19 09:41:20 volumio2 volumio[1366]: info: Discovery: Found device Volumio-AZ Jun 19 09:41:20 volumio2 volumio[1366]: info: Discovery: Connecting to remote: 192.168.1.11 Jun 19 09:41:20 volumio2 volumio[1366]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 19 09:41:20 volumio2 volumio[1366]: info: Reading ALSA contributions from plugins. Jun 19 09:41:20 volumio2 volumio[1366]: info: MPD Permissions set Jun 19 09:41:20 volumio2 volumio[1366]: info: MPD Permissions set Jun 19 09:41:20 volumio2 volumio[1366]: info: Volumio called home Jun 19 09:41:20 volumio2 volumio[1366]: info: Spotify config file written Jun 19 09:41:20 volumio2 volumio[1366]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 19 09:41:20 volumio2 sudo[1910]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 19 09:41:20 volumio2 sudo[1910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:20 volumio2 volumio[1366]: info: Discovery: Connected to remote: 192.168.1.11 Jun 19 09:41:20 volumio2 volumio[1366]: info: No need to fix Spotify hosts Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 19 09:41:20 volumio2 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 19 09:41:20 volumio2 go-librespot[1914]: go-librespot daemon starting... Jun 19 09:41:20 volumio2 sudo[1910]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 19 09:41:20 volumio2 go-librespot[1924]: time="2025-06-19T09:41:20+02:00" level=info msg="running go-librespot 0.2.0" Jun 19 09:41:20 volumio2 go-librespot[1924]: time="2025-06-19T09:41:20+02:00" level=debug msg="app state loaded" Jun 19 09:41:20 volumio2 go-librespot[1924]: time="2025-06-19T09:41:20+02:00" level=debug msg="stored credentials not found" Jun 19 09:41:20 volumio2 go-librespot[1924]: time="2025-06-19T09:41:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 19 09:41:20 volumio2 volumio[1366]: info: Starting Shairport Sync Jun 19 09:41:20 volumio2 volumio[1366]: info: Starting Shairport Sync Jun 19 09:41:20 volumio2 volumio[1366]: info: Starting Shairport Sync Jun 19 09:41:20 volumio2 sudo[1937]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 19 09:41:20 volumio2 sudo[1937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:41:20 volumio2 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 19 09:41:20 volumio2 sudo[1939]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 19 09:41:20 volumio2 sudo[1939]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:20 volumio2 sudo[1941]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 19 09:41:20 volumio2 sudo[1941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:20 volumio2 volumio[1366]: info: Asound.conf file unchanged, so no further update is needed Jun 19 09:41:20 volumio2 volumio[1366]: info: Output device has changed, restarting MPD Jun 19 09:41:20 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 19 09:41:20 volumio2 volumio[1366]: info: Output device has changed, restarting Shairport Sync Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:20 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:20 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 19 09:41:20 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 19 09:41:20 volumio2 systemd[1]: shairport-sync.service: Consumed 1.520s CPU time. Jun 19 09:41:20 volumio2 sudo[1948]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 19 09:41:20 volumio2 sudo[1948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:20 volumio2 sudo[1946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 19 09:41:20 volumio2 sudo[1946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:20 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 19 09:41:20 volumio2 volumio[1366]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 19 09:41:20 volumio2 sudo[1939]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:20 volumio2 sudo[1946]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:21 volumio2 sudo[1937]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:21 volumio2 sudo[1941]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:21 volumio2 systemd[1]: mpd.service: Deactivated successfully. Jun 19 09:41:21 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 19 09:41:21 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Jun 19 09:41:21 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 19 09:41:21 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 19 09:41:21 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 19 09:41:21 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 19 09:41:21 volumio2 volumio[1366]: info: MPD Permissions set Jun 19 09:41:21 volumio2 volumio[1366]: info: Shairport-Sync Started Jun 19 09:41:21 volumio2 volumio[1366]: Error adding Membership: Error: addMembership EINVAL Jun 19 09:41:21 volumio2 volumio[1366]: info: Shairport-Sync Started Jun 19 09:41:21 volumio2 volumio[1366]: info: Shairport-Sync Started Jun 19 09:41:21 volumio2 go-librespot[1924]: time="2025-06-19T09:41:21+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jun 19 09:41:21 volumio2 go-librespot[1924]: time="2025-06-19T09:41:21+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jun 19 09:41:21 volumio2 go-librespot[1924]: time="2025-06-19T09:41:21+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jun 19 09:41:21 volumio2 go-librespot[1924]: time="2025-06-19T09:41:21+02:00" level=info msg="zeroconf server listening on port 46319" Jun 19 09:41:21 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:21 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:21 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:21 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:21 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:21 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:21 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:21 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:21 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:21 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 19 09:41:21 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:21 volumio2 sudo[1972]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 19 09:41:21 volumio2 sudo[1972]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 19 09:41:21 volumio2 sudo[1982]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 19 09:41:21 volumio2 sudo[1972]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:21 volumio2 volumio[1366]: info: Starting Shairport Sync Jun 19 09:41:21 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:41:21 volumio2 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 19 09:41:21 volumio2 sudo[1985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 19 09:41:21 volumio2 sudo[1985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:21 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 19 09:41:21 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 19 09:41:21 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 19 09:41:21 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 19 09:41:21 volumio2 sudo[1985]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:21 volumio2 volumio[1366]: info: Shairport-Sync Started Jun 19 09:41:21 volumio2 volumio-remote-updater[811]: [2025-06-19 09:41:21] [connect] Successful connection Jun 19 09:41:21 volumio2 volumio[1366]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 19 09:41:21 volumio2 volumio-remote-updater[811]: [2025-06-19 09:41:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1750318881 101 Jun 19 09:41:21 volumio2 volumio[1366]: 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: 5 Jun 19 09:41:22 volumio2 mpd[1983]: 2025-06-19T09:41:22 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 19 09:41:22 volumio2 systemd[1]: Started mpd.service - Music Player Daemon. Jun 19 09:41:22 volumio2 sudo[1948]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:22 volumio2 sudo[1862]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:22 volumio2 sudo[1873]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:22 volumio2 volumio[1366]: info: Completed starting Core Plugins Jun 19 09:41:22 volumio2 volumio[1366]: info: ------------------------------------------- Jun 19 09:41:22 volumio2 volumio[1366]: info: ----- MyVolumio plugins startup ---- Jun 19 09:41:22 volumio2 volumio[1366]: info: ------------------------------------------- Jun 19 09:41:22 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 19 09:41:22 volumio2 volumio[1366]: error: MPD error: The expression evaluated to a falsy value: Jun 19 09:41:22 volumio2 volumio[1366]: assert.ok(self.idling) Jun 19 09:41:22 volumio2 volumio[1366]: error: The expression evaluated to a falsy value: Jun 19 09:41:22 volumio2 volumio[1366]: assert.ok(self.idling) Jun 19 09:41:22 volumio2 volumio[1366]: error: MPD error: The expression evaluated to a falsy value: Jun 19 09:41:22 volumio2 volumio[1366]: assert.ok(self.idling) Jun 19 09:41:22 volumio2 volumio[1366]: error: The expression evaluated to a falsy value: Jun 19 09:41:22 volumio2 volumio[1366]: assert.ok(self.idling) Jun 19 09:41:22 volumio2 volumio[1366]: info: MPD running with PID1983 Jun 19 09:41:22 volumio2 volumio[1366]: ,establishing connection Jun 19 09:41:22 volumio2 volumio[1366]: error: updateQueue error: null Jun 19 09:41:22 volumio2 volumio[1366]: error: updateQueue error: null Jun 19 09:41:22 volumio2 volumio[1366]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 19 09:41:22 volumio2 volumio[1366]: info: camilladsp service started and running in background, instance 1 Jun 19 09:41:22 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:22 volumio2 volumio[1366]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 768000 <-AAAAAAAAAAAAA Jun 19 09:41:22 volumio2 volumio[1366]: info: FusionDsp loaded Jun 19 09:41:22 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 09:41:22 volumio2 volumio[1366]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 19 09:41:22 volumio2 volumio[1366]: info: Adding Signal Path Element [object Object] Jun 19 09:41:22 volumio2 volumio[1366]: info: Adding fusiondspeq DSP Signal Path Element Jun 19 09:41:22 volumio2 volumio[1366]: info: FusionDsp - ---- installed callbackRead Jun 19 09:41:22 volumio2 sudo[2024]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 19 09:41:22 volumio2 sudo[2024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:22 volumio2 volumio[1366]: info: FusionDsp - File size found in array! Jun 19 09:41:22 volumio2 volumio[1366]: error: FusionDsp - Reload WebSocket error: [object Object] Jun 19 09:41:22 volumio2 systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 19 09:41:22 volumio2 sudo[2024]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:22 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 19 09:41:22 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetVisibleSources Jun 19 09:41:22 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 09:41:22 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:41:22 volumio2 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 19 09:41:22 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 19 09:41:22 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 19 09:41:22 volumio2 volumio[1366]: info: Received Get System Info Jun 19 09:41:22 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 09:41:22 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 09:41:22 volumio2 volumio[1366]: info: Discovery: Getting this device information Jun 19 09:41:22 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:41:22 volumio2 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 19 09:41:22 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 09:41:22 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:41:22 volumio2 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 19 09:41:22 volumio2 volumio[1366]: info: Listing playlists Jun 19 09:41:22 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 19 09:41:22 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 19 09:41:23 volumio2 volumio[1366]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 09:41:23 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:41:23 volumio2 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 19 09:41:23 volumio2 volumio[1366]: info: go-librespot daemon successfully initialized Jun 19 09:41:24 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 19 09:41:24 volumio2 volumio[1366]: info: Received Get System Info Jun 19 09:41:24 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 09:41:24 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 09:41:24 volumio2 volumio[1366]: info: Discovery: Getting this device information Jun 19 09:41:24 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:41:24 volumio2 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 19 09:41:24 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 09:41:24 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 19 09:41:25 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 19 09:41:25 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 19 09:41:25 volumio2 volumio[1366]: info: Received Get System Info Jun 19 09:41:25 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 09:41:25 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 09:41:25 volumio2 volumio[1366]: info: Discovery: Getting this device information Jun 19 09:41:25 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:41:25 volumio2 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 19 09:41:25 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 09:41:25 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 19 09:41:26 volumio2 volumio[1366]: info: Initializing connection to go-librespot Websocket Jun 19 09:41:26 volumio2 go-librespot[1924]: time="2025-06-19T09:41:26+02:00" level=debug msg="new websocket client" Jun 19 09:41:26 volumio2 volumio[1366]: info: Connection to go-librespot Websocket established Jun 19 09:41:27 volumio2 sudo[2056]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 19 09:41:27 volumio2 sudo[2056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:27 volumio2 sudo[2056]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:27 volumio2 sudo[2058]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 19 09:41:27 volumio2 sudo[2058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:27 volumio2 sudo[2058]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:27 volumio2 sudo[2060]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 19 09:41:27 volumio2 sudo[2060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:27 volumio2 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 19 09:41:27 volumio2 sudo[2060]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:27 volumio2 volumio[1366]: info: Upmpdcli Daemon Started Jun 19 09:41:27 volumio2 systemd[1]: systemd-fsckd.service: Deactivated successfully. Jun 19 09:41:29 volumio2 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jun 19 09:41:29 volumio2 volumio[1366]: info: Getting Spotify volume Jun 19 09:41:29 volumio2 volumio[1366]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 19 09:41:29 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:41:29 volumio2 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 19 09:41:30 volumio2 volumio[1366]: info: Adding plugin bluetooth to MyMusic Plugins Jun 19 09:41:30 volumio2 volumio[1366]: info: Adding plugin multiroom to MyMusic Plugins Jun 19 09:41:30 volumio2 volumio[1366]: info: Adding plugin metavolumio to MyMusic Plugins Jun 19 09:41:30 volumio2 volumio[1366]: info: Adding plugin cd_controller to MyMusic Plugins Jun 19 09:41:30 volumio2 volumio[1366]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 19 09:41:30 volumio2 volumio[1366]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 19 09:41:30 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 19 09:41:30 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:30 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:30 volumio2 volumio[1366]: info: Starting MyVolumio Remote Streaming Endpoints Jun 19 09:41:30 volumio2 volumio[1366]: info: MyVolumio login type: Token Jun 19 09:41:31 volumio2 volumio[1366]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 19 09:41:31 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 19 09:41:31 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 19 09:41:31 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 19 09:41:31 volumio2 volumio[1366]: info: Streaming services startup Jun 19 09:41:31 volumio2 volumio[1366]: info: Starting Streaming Daemon Jun 19 09:41:31 volumio2 sudo[2088]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 19 09:41:31 volumio2 sudo[2088]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:31 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 19 09:41:31 volumio2 sudo[2088]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:31 volumio2 volumio[1366]: error: Cannot start Volumio Streaming Daemon Jun 19 09:41:31 volumio2 volumio[1366]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 19 09:41:31 volumio2 volumio[1366]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 19 09:41:31 volumio2 volumio[1366]: error: MyVolumio Custom Token format not valid, refreshing it Jun 19 09:41:32 volumio2 volumio[1366]: info: MyVolumio login type: Token Jun 19 09:41:32 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 19 09:41:32 volumio2 volumio[1366]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 19 09:41:32 volumio2 volumio[1366]: info: MyVolumio token set successfully Jun 19 09:41:32 volumio2 volumio[1366]: info: MYVOLUMIO: Adding device Jun 19 09:41:32 volumio2 volumio[1366]: info: MYVOLUMIO: Evaluating Server Jun 19 09:41:33 volumio2 volumio[1366]: info: MyVolumio Plan changed: premium Jun 19 09:41:33 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jun 19 09:41:33 volumio2 volumio[1366]: info: Removing browser output: myVolumio user plan is not superstar Jun 19 09:41:33 volumio2 volumio[1366]: info: Removing audio output: Jun 19 09:41:33 volumio2 volumio[1366]: info: MYVOLUMIO: Adding device Jun 19 09:41:33 volumio2 volumio[1366]: info: MYVOLUMIO: Evaluating Server Jun 19 09:41:33 volumio2 volumio[1366]: info: Remote config written successfully Jun 19 09:41:33 volumio2 volumio[1366]: info: Starting Tunnel 1 Jun 19 09:41:33 volumio2 volumio[1366]: info: Starting Tunnel Connection Checker Jun 19 09:41:33 volumio2 volumio[1366]: info: Completed starting MyVolumio Plugin Jun 19 09:41:33 volumio2 volumio[1366]: info: MYVolumio Device enabled Jun 19 09:41:33 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jun 19 09:41:33 volumio2 volumio[1366]: info: MyVolumio status changed Jun 19 09:41:33 volumio2 volumio[1366]: info: Streaming services startup Jun 19 09:41:33 volumio2 volumio[1366]: info: Starting Streaming Daemon Jun 19 09:41:33 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jun 19 09:41:33 volumio2 volumio[1366]: error: [MyVolumio PluginManager] Cache data is invalid! Jun 19 09:41:33 volumio2 sudo[2129]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 19 09:41:33 volumio2 sudo[2129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:33 volumio2 sudo[2129]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:33 volumio2 volumio[1366]: error: Cannot start Volumio Streaming Daemon Jun 19 09:41:33 volumio2 volumio[1366]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 19 09:41:33 volumio2 volumio[1366]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 19 09:41:33 volumio2 volumio[1366]: info: Setting Geolocation for MyVolumio to eu6 Jun 19 09:41:33 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:33 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:33 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:33 volumio2 volumio[1366]: info: Setting Geolocation for MyVolumio to eu4 Jun 19 09:41:33 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:33 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:33 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:34 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! Jun 19 09:41:34 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jun 19 09:41:34 volumio2 volumio[1366]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Jun 19 09:41:34 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jun 19 09:41:34 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jun 19 09:41:34 volumio2 sudo[2135]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jun 19 09:41:34 volumio2 sudo[2135]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:34 volumio2 sudo[2135]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:34 volumio2 volumio[1366]: info: MRS: MultiRoom plugin initialized Jun 19 09:41:34 volumio2 volumio[1366]: info: MRS: STOPPING SNAPCLIENT Jun 19 09:41:34 volumio2 volumio[1366]: info: MRS: Snap server stop Jun 19 09:41:34 volumio2 volumio[1366]: info: MRS: STOPPING volumioStreaming Jun 19 09:41:34 volumio2 sudo[2152]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jun 19 09:41:34 volumio2 sudo[2152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:34 volumio2 sudo[2154]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jun 19 09:41:34 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jun 19 09:41:34 volumio2 sudo[2154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:34 volumio2 sudo[2158]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jun 19 09:41:34 volumio2 sudo[2158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:34 volumio2 sudo[2159]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jun 19 09:41:34 volumio2 sudo[2159]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:34 volumio2 sudo[2159]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:34 volumio2 sudo[2152]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:34 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jun 19 09:41:34 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jun 19 09:41:34 volumio2 sudo[2154]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:34 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jun 19 09:41:34 volumio2 sudo[2158]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:34 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jun 19 09:41:34 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jun 19 09:41:34 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jun 19 09:41:34 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jun 19 09:41:34 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jun 19 09:41:34 volumio2 volumio[1366]: info: Preparing to generate the ALSA configuration file Jun 19 09:41:34 volumio2 volumio[1366]: info: Updating MyVolumio device info Jun 19 09:41:34 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:34 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:34 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:34 volumio2 volumio[1366]: info: Updating MyVolumio device info Jun 19 09:41:34 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:34 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:34 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:34 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jun 19 09:41:34 volumio2 volumio[1366]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 19 09:41:34 volumio2 volumio[1366]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 19 09:41:34 volumio2 volumio[1366]: info: Reading ALSA contributions from plugins. Jun 19 09:41:34 volumio2 volumio[1366]: info: Successfully Added MyVolumio device Jun 19 09:41:34 volumio2 volumio[1366]: info: Successfully Added MyVolumio device Jun 19 09:41:34 volumio2 volumio[1366]: info: MRS: Removed streaming files Jun 19 09:41:34 volumio2 volumio[1366]: info: MRS: volumioStreaming STOPPED Jun 19 09:41:34 volumio2 volumio[1366]: info: MRS: SNAPSERVER STOPPED Jun 19 09:41:34 volumio2 volumio[1366]: info: MRS: SNAPCLIENT STOPPED Jun 19 09:41:34 volumio2 volumio[1366]: info: Asound.conf file written Jun 19 09:41:34 volumio2 sudo[2166]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 19 09:41:34 volumio2 sudo[2166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:34 volumio2 sudo[2166]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:34 volumio2 volumio[1366]: info: Output device has changed, restarting MPD Jun 19 09:41:34 volumio2 volumio[1366]: info: Output device has changed, restarting Shairport Sync Jun 19 09:41:34 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:34 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:34 volumio2 sudo[2172]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 19 09:41:34 volumio2 sudo[2172]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:34 volumio2 volumio[1366]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 19 09:41:34 volumio2 volumio[1366]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 19 09:41:34 volumio2 sudo[2172]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:34 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:34 volumio2 sudo[2174]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 19 09:41:34 volumio2 sudo[2174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:34 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jun 19 09:41:34 volumio2 volumio[1366]: ------------------------------------ BT MESSAGE: [FUNC] onStart Jun 19 09:41:34 volumio2 volumio[1366]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Jun 19 09:41:34 volumio2 volumio[1366]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Jun 19 09:41:34 volumio2 volumio[1366]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Jun 19 09:41:34 volumio2 volumio[1366]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Jun 19 09:41:34 volumio2 systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 19 09:41:34 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jun 19 09:41:34 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jun 19 09:41:34 volumio2 volumio[1366]: info: MRS: Pushing multiroomSync output for this device Jun 19 09:41:34 volumio2 volumio[1366]: info: MRS: Pushing multiroomSync output Jun 19 09:41:34 volumio2 volumio[1366]: info: Adding audio output: Jun 19 09:41:34 volumio2 volumio[1366]: info: Adding audio output: Jun 19 09:41:34 volumio2 volumio[1366]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 19 09:41:34 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:34 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jun 19 09:41:34 volumio2 volumio[1366]: info: Adding METAVOLUMIO REST API Endpoints Jun 19 09:41:34 volumio2 volumio[1366]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jun 19 09:41:34 volumio2 volumio[1366]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jun 19 09:41:34 volumio2 volumio[1366]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jun 19 09:41:34 volumio2 volumio[1366]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jun 19 09:41:34 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jun 19 09:41:34 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jun 19 09:41:34 volumio2 volumio[1366]: info: Preparing CD Folders Jun 19 09:41:34 volumio2 volumio[1366]: info: Adding CD REST API Endpoints Jun 19 09:41:34 volumio2 volumio[1366]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jun 19 09:41:34 volumio2 volumio[1366]: info: Starting UDEV Watcher for CD Jun 19 09:41:34 volumio2 volumio[1366]: info: Detecting CD presence with UDEV Jun 19 09:41:34 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jun 19 09:41:34 volumio2 bluetoothd[799]: Path / reserved for Adv Monitor app :1.31 Jun 19 09:41:34 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jun 19 09:41:34 volumio2 volumio[1366]: info: Adding inputs REST Endpoints Jun 19 09:41:34 volumio2 volumio[1366]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jun 19 09:41:34 volumio2 volumio[1366]: info: Scanning Audio Inputs Jun 19 09:41:34 volumio2 bluetoothd[799]: Adv Monitor app :1.31 disconnected from D-Bus Jun 19 09:41:35 volumio2 volumio[1366]: info: Checking against Known Cards name Jun 19 09:41:35 volumio2 volumio[1366]: info: Checking against Known Cards name Jun 19 09:41:35 volumio2 volumio[1366]: info: Checking against Known Cards name Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 09:41:35 volumio2 volumio[1366]: info: [1750318895022] CoreMusicLibrary::Adding element USB Audio CODEC Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 09:41:35 volumio2 volumio[1366]: Cannot find translation for source USB Audio CODEC Jun 19 09:41:35 volumio2 volumio[1366]: info: Checking against Known Cards name Jun 19 09:41:35 volumio2 volumio[1366]: info: Adding Server instance for streaming Jun 19 09:41:35 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jun 19 09:41:35 volumio2 volumio[1366]: error: Hi Res Audio Failed Login: Missing Login Data Jun 19 09:41:35 volumio2 volumio[1366]: info: Adding HIGHRESAUDIO REST API Endpoints Jun 19 09:41:35 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jun 19 09:41:35 volumio2 volumio[1366]: info: Refreshing TIDAL token Jun 19 09:41:35 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jun 19 09:41:35 volumio2 volumio[1366]: info: Refreshing QOBUZ token Jun 19 09:41:35 volumio2 volumio[1366]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jun 19 09:41:35 volumio2 systemd[1]: mpd.service: Deactivated successfully. Jun 19 09:41:35 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 19 09:41:35 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Jun 19 09:41:35 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 19 09:41:35 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 19 09:41:35 volumio2 sudo[2193]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 19 09:41:35 volumio2 sudo[2193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:35 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 19 09:41:35 volumio2 volumio[1366]: info: MRS: Adding multiroomSync output Jun 19 09:41:35 volumio2 volumio[1366]: info: Adding audio output: Jun 19 09:41:35 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 19 09:41:35 volumio2 volumio[1366]: info: MRS: Pushing multiroomSync output Jun 19 09:41:35 volumio2 volumio[1366]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Jun 19 09:41:35 volumio2 volumio[1366]: info: MPD Permissions set Jun 19 09:41:35 volumio2 sudo[2193]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:35 volumio2 volumio[1366]: info: Starting Shairport Sync Jun 19 09:41:35 volumio2 sudo[2196]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 19 09:41:35 volumio2 sudo[2196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 19 09:41:35 volumio2 sudo[2209]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 19 09:41:35 volumio2 sudo[2198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jun 19 09:41:35 volumio2 sudo[2196]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:35 volumio2 sudo[2198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:35 volumio2 sudo[2209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:35 volumio2 volumio[1366]: info: Access Token successfully retrieved Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 09:41:35 volumio2 volumio[1366]: info: [1750318895157] CoreMusicLibrary::Adding element QOBUZ Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 09:41:35 volumio2 volumio[1366]: Cannot find translation for source USB Audio CODEC Jun 19 09:41:35 volumio2 volumio[1366]: Cannot find translation for source QOBUZ Jun 19 09:41:35 volumio2 volumio[1366]: info: Stopping AccessToken refresher cron for QOBUZ Jun 19 09:41:35 volumio2 volumio[1366]: info: AccessToken refresher cron started for QOBUZ Jun 19 09:41:35 volumio2 volumio[1366]: info: Adding QOBUZ REST API Endpoints Jun 19 09:41:35 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 19 09:41:35 volumio2 systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Jun 19 09:41:35 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 19 09:41:35 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 19 09:41:35 volumio2 systemd[1]: shairport-sync.service: Consumed 1.564s CPU time. Jun 19 09:41:35 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 19 09:41:35 volumio2 sudo[2198]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:35 volumio2 volumio[1366]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Jun 19 09:41:35 volumio2 sudo[2209]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:35 volumio2 volumiobt[2216]: INFO [BTSTART] Ensuring Bluetooth directory exists... Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreCommandRouter::volumioStop Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreStateMachine::stop Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 09:41:35 volumio2 volumio[1366]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 09:41:35 volumio2 volumio[1366]: info: Shairport-Sync Started Jun 19 09:41:35 volumio2 sudo[2217]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Jun 19 09:41:35 volumio2 sudo[2217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:35 volumio2 volumio[1366]: info: MRS: Found cast device: Pioneer-SX-S30DAB-92929d1214fa9756f0b255389524de8b Jun 19 09:41:35 volumio2 volumio[1366]: info: Adding audio output: Jun 19 09:41:35 volumio2 volumio[1366]: info: MRS: Found cast device: LenovoCD-24502F-1797cd035674a6bd04799d93772b0005 Jun 19 09:41:35 volumio2 volumio[1366]: info: Adding audio output: Jun 19 09:41:35 volumio2 sudo[2217]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:35 volumio2 sudo[2235]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Jun 19 09:41:35 volumio2 sudo[2235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:35 volumio2 volumio[1366]: info: Successfully Updated MyVolumio device Jun 19 09:41:35 volumio2 sudo[2235]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:35 volumio2 volumiobt[2237]: INFO [BTSTART] Powering on Bluetooth if needed... Jun 19 09:41:35 volumio2 bluetoothd[799]: Adv Monitor app :1.36 disconnected from D-Bus Jun 19 09:41:35 volumio2 volumiobt[2240]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Jun 19 09:41:35 volumio2 volumiobt[2241]: [75B blob data] Jun 19 09:41:35 volumio2 volumiobt[2241]: [bluetooth]# pairable on Jun 19 09:41:35 volumio2 volumiobt[2241]: [54B blob data] Jun 19 09:41:35 volumio2 volumio[1366]: info: Access Token successfully retrieved Jun 19 09:41:35 volumio2 volumiobt[2241]: [72B blob data] Jun 19 09:41:35 volumio2 volumiobt[2241]: [68B blob data] Jun 19 09:41:35 volumio2 bluetoothd[799]: Path / reserved for Adv Monitor app :1.37 Jun 19 09:41:35 volumio2 bluetoothd[799]: Adv Monitor app :1.37 disconnected from D-Bus Jun 19 09:41:35 volumio2 volumiobt[2241]: [bluetooth]# Jun 19 09:41:35 volumio2 volumiobt[2242]: INFO [BTSTART] Registering Bluetooth agent... Jun 19 09:41:35 volumio2 bluetoothd[799]: Adv Monitor app :1.38 disconnected from D-Bus Jun 19 09:41:35 volumio2 volumiobt[2244]: No agent is registered Jun 19 09:41:35 volumio2 bluetoothd[799]: Adv Monitor app :1.39 disconnected from D-Bus Jun 19 09:41:35 volumio2 volumiobt[2245]: INFO [BTSTART] Agent registered successfully. Jun 19 09:41:35 volumio2 volumiobt[2246]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Jun 19 09:41:35 volumio2 volumio[1366]: info: Adding audio output: Jun 19 09:41:35 volumio2 volumio[1366]: info: Adding audio output: Jun 19 09:41:35 volumio2 volumio[1366]: info: Adding audio output: Jun 19 09:41:35 volumio2 volumio[1366]: info: Adding audio output: Jun 19 09:41:35 volumio2 volumio[1366]: info: Adding audio output: Jun 19 09:41:35 volumio2 volumio[1366]: info: Successfully Updated MyVolumio device Jun 19 09:41:35 volumio2 volumio[1366]: info: Successfully retrieved User Session From TIDAL Jun 19 09:41:35 volumio2 volumiobt[2247]: INFO:a2dp-agent:Connecting to system bus. Jun 19 09:41:35 volumio2 volumio[1366]: info: Successfully retrieved User Subscription From TIDAL Jun 19 09:41:35 volumio2 volumio[1366]: info: Adding TIDAL to Browse Sources Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 09:41:35 volumio2 volumio[1366]: info: [1750318895695] CoreMusicLibrary::Adding element TIDAL Jun 19 09:41:35 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 09:41:35 volumio2 volumio[1366]: Cannot find translation for source USB Audio CODEC Jun 19 09:41:35 volumio2 volumio[1366]: Cannot find translation for source QOBUZ Jun 19 09:41:35 volumio2 volumio[1366]: Cannot find translation for source TIDAL Jun 19 09:41:35 volumio2 volumio[1366]: info: Stopping AccessToken refresher cron Jun 19 09:41:35 volumio2 volumiobt[2247]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Jun 19 09:41:35 volumio2 volumiobt[2247]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Jun 19 09:41:35 volumio2 volumio[1366]: info: AccessToken refresher cron started Jun 19 09:41:35 volumio2 volumio[1366]: info: Adding TIDAL REST API Endpoints Jun 19 09:41:35 volumio2 volumiobt[2247]: INFO:a2dp-agent:Registering agent. Jun 19 09:41:35 volumio2 volumiobt[2247]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Jun 19 09:41:35 volumio2 volumiobt[2247]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Jun 19 09:41:35 volumio2 mpd[2213]: 2025-06-19T09:41:35 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 19 09:41:35 volumio2 systemd[1]: Started mpd.service - Music Player Daemon. Jun 19 09:41:35 volumio2 sudo[2174]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:35 volumio2 volumio[1366]: error: updateQueue error: null Jun 19 09:41:35 volumio2 volumio[1366]: info: Adding audio output: Jun 19 09:41:38 volumio2 volumio[1366]: info: TidalConnect service stoped! Jun 19 09:41:38 volumio2 volumio[1366]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 19 09:41:38 volumio2 volumio[1366]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 19 09:41:38 volumio2 sudo[2278]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 19 09:41:38 volumio2 sudo[2278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:38 volumio2 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jun 19 09:41:38 volumio2 sudo[2278]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:38 volumio2 sudo[2289]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jun 19 09:41:38 volumio2 sudo[2289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:41:38 volumio2 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. Jun 19 09:41:38 volumio2 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. Jun 19 09:41:38 volumio2 systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jun 19 09:41:38 volumio2 sudo[2289]: pam_unix(sudo:session): session closed for user root Jun 19 09:41:38 volumio2 volumio[1366]: info: Remote SSH Started Jun 19 09:41:38 volumio2 autossh[2292]: port set to 0, monitoring disabled Jun 19 09:41:38 volumio2 autossh[2292]: starting ssh (count 1) Jun 19 09:41:38 volumio2 autossh[2292]: ssh child pid is 2295 Jun 19 09:41:38 volumio2 volumio[1366]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 19 09:41:38 volumio2 volumio[1366]: info: Executing endpoint tc_getconfig Jun 19 09:41:38 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 19 09:41:38 volumio2 vtcs[2284]: OpenSSL version mismatch. Built against 101010ef, you have 30000100 Jun 19 09:41:38 volumio2 vtcs[2284]: STARTING TidalConnect services, version: 1.5.0.45 Jun 19 09:41:38 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:41:38 volumio2 volumio[1366]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KmhI4VNLmgfYPsYBmUr34lKfrfO2.25c24366becc66fc5dbb8e71214777ce.state.status' Jun 19 09:41:38 volumio2 volumiossh-tunnel[2295]: Warning: Permanently added '[eu4.myvolumio.org]:2222' (RSA) to the list of known hosts. Jun 19 09:41:38 volumio2 vtcs[2284]: STARTED TidalConnect services. Jun 19 09:41:38 volumio2 volumio[1366]: info: Executing endpoint tc_connect Jun 19 09:41:38 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 19 09:41:38 volumio2 volumio[1366]: info: Connecting to TidalConnect Jun 19 09:41:38 volumio2 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 19 09:41:38 volumio2 volumio[1366]: info: CoreStateMachine::pushState Jun 19 09:41:38 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 09:41:38 volumio2 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 19 09:41:38 volumio2 volumio[1366]: info: MRS: Pushing multiroomSync output update for this device Jun 19 09:41:38 volumio2 volumio[1366]: info: MRS: Pushing multiroomSync output Jun 19 09:41:38 volumio2 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 19 09:41:38 volumio2 volumio[1366]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Jun 19 09:41:38 volumio2 volumio[1366]: info: CoreCommandRouter::servicePushState Jun 19 09:41:38 volumio2 volumio[1366]: info: CoreStateMachine::pushState Jun 19 09:41:38 volumio2 volumio[1366]: info: CoreCommandRouter::volumioPushState Jun 19 09:41:38 volumio2 volumio[1366]: info: MRS: Pushing multiroomSync output update for this device Jun 19 09:41:38 volumio2 volumio[1366]: info: MRS: Pushing multiroomSync output Jun 19 09:41:38 volumio2 volumio[1366]: info: CorePlayQueue::getTrack 0 Jun 19 09:41:38 volumio2 volumio[1366]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Jun 19 09:41:38 volumio2 vtcs[2284]: [2025-06-19 09:41:38.340] [tisoc] [error] [WebsocketServerTls.cpp:90] [WebsocketServerTls onTlsInit Error] use_certificate_chain: no start line Jun 19 09:41:38 volumio2 volumio[1366]: info: FusionDsp - Volumio is not playing Jun 19 09:41:38 volumio2 volumio[1366]: info: FusionDsp - Clipped samples monitor stopped Jun 19 09:41:38 volumio2 volumio[1366]: info: FusionDsp - Volumio is not playing Jun 19 09:41:38 volumio2 volumio[1366]: info: FusionDsp - Clipped samples monitor stopped Jun 19 09:41:38 volumio2 volumio[1366]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KmhI4VNLmgfYPsYBmUr34lKfrfO2.25c24366becc66fc5dbb8e71214777ce.state.status' Jun 19 09:41:38 volumio2 upmpdcli[2310]: writing RSA key Jun 19 09:41:39 volumio2 volumio[1366]: info: MRS: Getting audio outputs on start Jun 19 09:41:39 volumio2 volumio[1366]: info: MRS: Requesting all other devices output Jun 19 09:41:40 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 19 09:41:40 volumio2 volumio[1366]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 19 09:41:40 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 19 09:41:40 volumio2 volumio[1366]: info: Received Get System Version Jun 19 09:41:40 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 09:41:40 volumio2 volumio[1366]: info: Received Get System Info Jun 19 09:41:40 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 09:41:40 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 09:41:40 volumio2 volumio[1366]: info: Discovery: Getting this device information Jun 19 09:41:40 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:41:40 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 09:41:40 volumio2 volumio[1366]: info: Executing endpoint metavolumio Jun 19 09:41:40 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 19 09:41:41 volumio2 volumio[1366]: info: TidalConnect service started! Jun 19 09:41:41 volumio2 volumio[1366]: [Metrics] CommandRouter: 25s 896.20ms Jun 19 09:41:41 volumio2 volumio[1366]: info: CoreCommandRouter::volumiosetStartupVolume Jun 19 09:41:41 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:41 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:41 volumio2 volumio[1366]: info: CoreCommandRouter::Close All Modals sent Jun 19 09:41:41 volumio2 volumio[1366]: info: CoreCommandRouter::Close All Modals sent Jun 19 09:41:41 volumio2 volumio[1366]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 19 09:41:41 volumio2 volumio[1366]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 19 09:41:41 volumio2 volumio[1366]: info: camilladsp stopping service pid 2017... Jun 19 09:41:41 volumio2 volumio[1366]: info: camilladsp service terminated, instance 1 Jun 19 09:41:41 volumio2 volumio[1366]: info: FusionDsp - File size found in array! Jun 19 09:41:41 volumio2 volumio[1366]: info: camilladsp service started and running in background, instance 1 Jun 19 09:41:42 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 19 09:41:42 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 19 09:41:42 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 19 09:41:45 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 19 09:41:45 volumio2 volumio[1366]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 19 09:41:46 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:41:48 volumio2 volumio[1366]: info: BOOT COMPLETED Jun 19 09:41:50 volumio2 systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 19 09:41:50 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 09:41:50 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:41:50 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 19 09:41:50 volumio2 volumio-remote-updater[811]: Test mode enabled Jun 19 09:41:50 volumio2 volumio-remote-updater[811]: Alpha mode disabled Jun 19 09:41:50 volumio2 volumio-remote-updater[811]: Alpha legacy test mode disabled Jun 19 09:41:50 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 19 09:41:50 volumio2 volumio[1366]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 19 09:41:50 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 19 09:41:50 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:50 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:41:50 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 19 09:41:50 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 19 09:41:50 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 19 09:41:50 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetBrowseSources Jun 19 09:41:50 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 09:42:11 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 09:42:11 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 09:42:11 volumio2 volumio[1366]: info: Discovery: Getting this device information Jun 19 09:42:11 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:11 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 09:42:11 volumio2 volumio[1366]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.159 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Jun 19 09:42:11 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:11 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 19 09:42:11 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 19 09:42:12 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 09:42:12 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 09:42:12 volumio2 volumio[1366]: info: Discovery: Getting this device information Jun 19 09:42:12 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:12 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 09:42:15 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:16 volumio2 volumio[1366]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.159 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Jun 19 09:42:16 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:16 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 19 09:42:16 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 19 09:42:16 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:16 volumio2 sudo[2428]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 19 09:42:16 volumio2 sudo[2428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:16 volumio2 sudo[2430]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 19 09:42:16 volumio2 sudo[2428]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:16 volumio2 sudo[2430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:16 volumio2 sudo[2430]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:16 volumio2 volumio[1366]: verbose: New Socket.io Connection to 192.168.1.215 from 192.168.1.159 UA: Mozilla/5.0 (Linux; Android 15; SM-S921B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jun 19 09:42:16 volumio2 sudo[2434]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 19 09:42:16 volumio2 sudo[2434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:16 volumio2 sudo[2436]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 19 09:42:16 volumio2 sudo[2434]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:16 volumio2 sudo[2436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:16 volumio2 sudo[2436]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:17 volumio2 volumio[1366]: verbose: New Socket.io Connection to 192.168.1.215 from 192.168.1.159 UA: Mozilla/5.0 (Linux; Android 15; SM-S921B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Jun 19 09:42:17 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 19 09:42:17 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetVisibleSources Jun 19 09:42:17 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 09:42:17 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:17 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 19 09:42:17 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 19 09:42:17 volumio2 volumio[1366]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 19 09:42:17 volumio2 volumio[1366]: info: Received Get System Info Jun 19 09:42:17 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 09:42:17 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 09:42:17 volumio2 volumio[1366]: info: Discovery: Getting this device information Jun 19 09:42:17 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:17 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 09:42:17 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:17 volumio2 volumio[1366]: info: Listing playlists Jun 19 09:42:17 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 19 09:42:17 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 19 09:42:17 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 19 09:42:17 volumio2 volumio[1366]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 09:42:18 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetQueue Jun 19 09:42:18 volumio2 volumio[1366]: info: CoreStateMachine::getQueue Jun 19 09:42:18 volumio2 volumio[1366]: info: CorePlayQueue::getQueue Jun 19 09:42:18 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 19 09:42:18 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 19 09:42:18 volumio2 volumio[1366]: info: Received Get System Info Jun 19 09:42:18 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 09:42:18 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 09:42:18 volumio2 volumio[1366]: info: Discovery: Getting this device information Jun 19 09:42:18 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:18 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 09:42:19 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 19 09:42:19 volumio2 volumio[1366]: info: Received Get System Info Jun 19 09:42:19 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 09:42:19 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 09:42:19 volumio2 volumio[1366]: info: Discovery: Getting this device information Jun 19 09:42:19 volumio2 volumio[1366]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:19 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 09:42:25 volumio2 volumio[1366]: Searching all installed plugins Jun 19 09:42:25 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 09:42:25 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: , search Jun 19 09:42:25 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jun 19 09:42:25 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jun 19 09:42:25 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jun 19 09:42:25 volumio2 volumio[1366]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jun 19 09:42:25 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 19 09:42:25 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: smart_inputs , search Jun 19 09:42:25 volumio2 volumio[1366]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin smart_inputs Jun 19 09:42:25 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Jun 19 09:42:25 volumio2 volumio[1366]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 19 09:42:25 volumio2 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 19 09:42:25 volumio2 dbus-daemon[800]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.43' (uid=0 pid=2465 comm="timedatectl show --property=NTPSynchronized --valu") Jun 19 09:42:25 volumio2 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 19 09:42:25 volumio2 dbus-daemon[800]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 19 09:42:25 volumio2 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 19 09:42:25 volumio2 setdatetime-helper.sh[2464]: Time is already synchronized. Jun 19 09:42:25 volumio2 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 19 09:42:25 volumio2 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 19 09:42:25 volumio2 volumio[1366]: info: searchTIDALUri took 452 milliseconds Jun 19 09:42:25 volumio2 volumio[1366]: info: search took 452 milliseconds Jun 19 09:42:26 volumio2 volumio[1366]: Use XMLElement.setAttribute instead Jun 19 09:42:26 volumio2 volumio[1366]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 09:42:26 volumio2 volumio[1366]: TypeError: children[i].attr(...).value is not a function Jun 19 09:42:26 volumio2 volumio[1366]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Jun 19 09:42:26 volumio2 volumio[1366]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 19 09:42:26 volumio2 volumio[1366]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 09:42:26 volumio2 sudo[2483]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-19 09:41' Jun 19 09:42:26 volumio2 sudo[2483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:26 volumio2 sudo[2483]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:26 volumio2 vtcs[2284]: [2025-06-19 09:42:26.450] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jun 19 09:42:26 volumio2 volumio-remote-updater[811]: [2025-06-19 09:42:26] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 19 09:42:26 volumio2 volumio-remote-updater[811]: [2025-06-19 09:42:26] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 19 09:42:26 volumio2 go-librespot[1924]: time="2025-06-19T09:42:26+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jun 19 09:42:26 volumio2 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 19 09:42:26 volumio2 systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 19 09:42:26 volumio2 systemd[1]: volumio.service: Consumed 17.643s CPU time. Jun 19 09:42:26 volumio2 systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 19 09:42:26 volumio2 systemd[1]: dynamicswap.service: Deactivated successfully. Jun 19 09:42:26 volumio2 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jun 19 09:42:26 volumio2 systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 19 09:42:26 volumio2 systemd[1]: Stopped volumio.service - Volumio Backend Module. Jun 19 09:42:26 volumio2 systemd[1]: volumio.service: Consumed 17.643s CPU time. Jun 19 09:42:26 volumio2 systemd[1]: Started volumio.service - Volumio Backend Module. Jun 19 09:42:26 volumio2 systemd[1]: dynamicswap.service: Deactivated successfully. Jun 19 09:42:27 volumio2 volumio[2515]: info: ------------------------------------------- Jun 19 09:42:27 volumio2 volumio[2515]: info: ----- Volumio3 ---- Jun 19 09:42:27 volumio2 volumio[2515]: info: ------------------------------------------- Jun 19 09:42:27 volumio2 volumio[2515]: info: ----- System startup ---- Jun 19 09:42:27 volumio2 volumio[2515]: info: ------------------------------------------- Jun 19 09:42:27 volumio2 volumio[2515]: info: MYVOLUMIO Environment detected Jun 19 09:42:27 volumio2 volumio[2515]: info: Plugin folders cleanup Jun 19 09:42:27 volumio2 volumio[2515]: info: Scanning into folder /volumio/app/plugins/ Jun 19 09:42:27 volumio2 volumio[2515]: info: Scanning category audio_interface Jun 19 09:42:27 volumio2 volumio[2515]: info: Scanning category miscellanea Jun 19 09:42:27 volumio2 volumio[2515]: info: Scanning category music_service Jun 19 09:42:27 volumio2 volumio[2515]: info: Scanning category plugins.json Jun 19 09:42:27 volumio2 volumio[2515]: info: Scanning category system_controller Jun 19 09:42:27 volumio2 volumio[2515]: info: Scanning category user_interface Jun 19 09:42:27 volumio2 volumio[2515]: info: Scanning into folder /data/plugins/ Jun 19 09:42:27 volumio2 volumio[2515]: info: Scanning category audio_interface Jun 19 09:42:27 volumio2 volumio[2515]: info: Scanning category music_service Jun 19 09:42:27 volumio2 volumio[2515]: info: Scanning category user_interface Jun 19 09:42:27 volumio2 volumio[2515]: info: Plugin folders cleanup completed Jun 19 09:42:27 volumio2 volumio[2515]: info: ------------------------------------------- Jun 19 09:42:27 volumio2 volumio[2515]: info: ----- Core plugins startup ---- Jun 19 09:42:27 volumio2 volumio[2515]: info: ------------------------------------------- Jun 19 09:42:27 volumio2 volumio[2515]: info: Loading plugins from folder /volumio/app/plugins/ Jun 19 09:42:27 volumio2 volumio[2515]: info: Adding plugin upnp to MyMusic Plugins Jun 19 09:42:27 volumio2 volumio[2515]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 19 09:42:27 volumio2 volumio[2515]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 19 09:42:27 volumio2 volumio[2515]: info: Loading plugins from folder /data/plugins/ Jun 19 09:42:27 volumio2 volumio[2515]: info: Loading plugin "system"... Jun 19 09:42:27 volumio2 volumio[2515]: info: Loading plugin "appearance"... Jun 19 09:42:27 volumio2 volumio[2515]: info: Loading plugin "network"... Jun 19 09:42:27 volumio2 volumio[2515]: info: Refreshing Cached IP Addresses Jun 19 09:42:27 volumio2 sudo[2544]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 19 09:42:27 volumio2 sudo[2544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:27 volumio2 sudo[2546]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 19 09:42:27 volumio2 sudo[2546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:27 volumio2 volumio[2515]: info: Loading plugin "services"... Jun 19 09:42:27 volumio2 sudo[2544]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:27 volumio2 volumio[2515]: info: Loading plugin "alsa_controller"... Jun 19 09:42:27 volumio2 sudo[2546]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:27 volumio2 sudo[2557]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 19 09:42:27 volumio2 sudo[2557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:27 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 09:42:27 volumio2 volumio[2515]: info: Loading plugin "wizard"... Jun 19 09:42:27 volumio2 volumio[2515]: info: Loading plugin "networkfs"... Jun 19 09:42:27 volumio2 volumio[2515]: info: Starting Udev Watcher for removable devices Jun 19 09:42:27 volumio2 volumio[2515]: info: Ignoring mount for partition: boot Jun 19 09:42:27 volumio2 volumio[2515]: info: Ignoring mount for partition: volumio Jun 19 09:42:27 volumio2 volumio[2515]: info: Ignoring mount for partition: volumio_data Jun 19 09:42:27 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 19 09:42:27 volumio2 volumio[2515]: info: Loading plugin "volumio_command_line_client"... Jun 19 09:42:27 volumio2 volumio[2515]: info: Loading plugin "upnp"... Jun 19 09:42:27 volumio2 volumio[2515]: info: [1750318947759] Starting Upmpd Daemon Jun 19 09:42:27 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 19 09:42:27 volumio2 volumio[2515]: info: Loading plugin "my_music"... Jun 19 09:42:27 volumio2 volumio[2515]: info: Loading plugin "mpd"... Jun 19 09:42:27 volumio2 volumio[2515]: info: Loading plugin "upnp_browser"... Jun 19 09:42:28 volumio2 volumio[2515]: info: Loading plugin "alarm-clock"... Jun 19 09:42:28 volumio2 volumio[2515]: info: Loading plugin "airplay_emulation"... Jun 19 09:42:28 volumio2 volumio[2515]: info: Starting Shairport Sync Jun 19 09:42:28 volumio2 volumio[2515]: info: Loading plugin "last_100"... Jun 19 09:42:28 volumio2 volumio[2515]: info: Loading plugin "webradio"... Jun 19 09:42:28 volumio2 volumio[2515]: info: Loading plugin "i2s_dacs"... Jun 19 09:42:28 volumio2 volumio[2515]: info: I2S DAC not set, start Auto-detection Jun 19 09:42:28 volumio2 volumio[2515]: info: Loading plugin "volumiodiscovery"... Jun 19 09:42:28 volumio2 volumio[2515]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 19 09:42:28 volumio2 volumio[2515]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 19 09:42:28 volumio2 volumio[2515]: *** WARNING *** For more information see Jun 19 09:42:28 volumio2 volumio[2515]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 19 09:42:28 volumio2 volumio[2515]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 19 09:42:28 volumio2 node[2515]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 19 09:42:28 volumio2 volumio[2515]: *** WARNING *** For more information see Jun 19 09:42:28 volumio2 node[2515]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 19 09:42:28 volumio2 node[2515]: *** WARNING *** For more information see Jun 19 09:42:28 volumio2 node[2515]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 19 09:42:28 volumio2 node[2515]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 19 09:42:28 volumio2 node[2515]: *** WARNING *** For more information see Jun 19 09:42:28 volumio2 volumio[2515]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 19 09:42:28 volumio2 volumio[2515]: info: Discovery: Started advertising with name: Volumio2 Jun 19 09:42:28 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 19 09:42:28 volumio2 volumio[2515]: info: Loading plugin "spop"... Jun 19 09:42:28 volumio2 volumio[2515]: info: Loading plugin "now_playing"... Jun 19 09:42:28 volumio2 sudo[2557]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:29 volumio2 volumio[2515]: info: Loading plugin "outputs"... Jun 19 09:42:29 volumio2 volumio[2515]: info: Loading plugin "albumart"... Jun 19 09:42:29 volumio2 volumio[2515]: info: Plugin example_plugin is not enabled Jun 19 09:42:29 volumio2 volumio[2515]: info: Loading plugin "inputs"... Jun 19 09:42:29 volumio2 volumio[2515]: info: Loading plugin "updater_comm"... Jun 19 09:42:29 volumio2 volumio[2515]: info: Plugin mpdemulation is not enabled Jun 19 09:42:29 volumio2 volumio[2515]: info: Loading plugin "rest_api"... Jun 19 09:42:29 volumio2 volumio[2515]: info: Loading plugin "websocket"... Jun 19 09:42:29 volumio2 volumio[2515]: info: Starting Socket.io Server version 1.7.4 Jun 19 09:42:29 volumio2 volumio[2515]: info: Loading plugin "fusiondsp"... Jun 19 09:42:29 volumio2 volumio[2577]: Forking 3 albumart workers Jun 19 09:42:29 volumio2 volumio[2515]: info: Applying required configuration parameters for plugin fusiondsp Jun 19 09:42:29 volumio2 volumio[2515]: info: Loading i18n strings for locale de Jun 19 09:42:29 volumio2 volumio[2515]: Updating browse sources language Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::initPlayerControls Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: Express server listening on port 3000 Jun 19 09:42:29 volumio2 volumio[2515]: [Metrics] WebUI: 2s 232.43ms Jun 19 09:42:29 volumio2 volumio[2515]: info: Setting Device type: x86 Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreStateMachine::resetVolumioState Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreStateMachine::getcurrentVolume Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::volumioRetrievevolume Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreStateMachine::pushState Jun 19 09:42:29 volumio2 volumio[2515]: info: CorePlayQueue::getTrack 0 Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::volumioPushState Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 19 09:42:29 volumio2 volumio[2515]: info: Completed loading Core Plugins Jun 19 09:42:29 volumio2 volumio[2515]: info: Preparing to generate the ALSA configuration file Jun 19 09:42:29 volumio2 volumio[2590]: Starting albumart workers Jun 19 09:42:29 volumio2 volumio[2515]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 19 09:42:29 volumio2 volumio[2515]: info: Reading ALSA contributions from plugins. Jun 19 09:42:29 volumio2 volumio[2588]: Starting albumart workers Jun 19 09:42:29 volumio2 volumio[2515]: info: Volumio Network Manager: Network status updated: 2 Jun 19 09:42:29 volumio2 volumio[2515]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 19 09:42:29 volumio2 volumio[2589]: Starting albumart workers Jun 19 09:42:29 volumio2 volumio[2515]: verbose: New Socket.io Connection to 192.168.1.215 from 192.168.1.159 UA: Mozilla/5.0 (Linux; Android 15; SM-S921B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 19 09:42:29 volumio2 volumio[2515]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.11 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 19 09:42:29 volumio2 volumio[2515]: info: Reloading queue from file Jun 19 09:42:29 volumio2 volumio[2515]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreStateMachine::setRepeat null single undefined Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreStateMachine::pushState Jun 19 09:42:29 volumio2 volumio[2515]: info: CorePlayQueue::getTrack 0 Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::volumioPushState Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreStateMachine::setRandom null Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreStateMachine::pushState Jun 19 09:42:29 volumio2 volumio[2515]: info: CorePlayQueue::getTrack 0 Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::volumioPushState Jun 19 09:42:29 volumio2 volumio[2515]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.159 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Jun 19 09:42:29 volumio2 volumio[2515]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.11 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 19 09:42:29 volumio2 volumio[2515]: info: Discovery: adding 04720723-0b84-4186-8f09-f9ac9b9f00f9 Jun 19 09:42:29 volumio2 volumio[2515]: info: Discovery: Found device Volumio-AZ Jun 19 09:42:29 volumio2 volumio[2515]: info: Discovery: Connecting to remote: 192.168.1.11 Jun 19 09:42:29 volumio2 volumio[2515]: info: Discovery: adding 6b56f2d5-cbd9-4eb4-af79-a9ffa3dae30f Jun 19 09:42:29 volumio2 volumio[2515]: info: Discovery: Found device Volumio2 Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:29 volumio2 volumio[2515]: info: CorePlayQueue::getTrack 0 Jun 19 09:42:29 volumio2 volumio[2515]: info: Discovery: this is already registered, 6b56f2d5-cbd9-4eb4-af79-a9ffa3dae30f Jun 19 09:42:29 volumio2 volumio[2515]: info: Discovery: Found device Volumio2 Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:29 volumio2 volumio[2515]: info: CorePlayQueue::getTrack 0 Jun 19 09:42:29 volumio2 volumio[2515]: info: Asound.conf file written Jun 19 09:42:29 volumio2 sudo[2636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 19 09:42:29 volumio2 sudo[2636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:29 volumio2 sudo[2636]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:29 volumio2 volumio[2515]: info: Output device has changed, restarting MPD Jun 19 09:42:29 volumio2 volumio[2515]: info: Output device has changed, restarting Shairport Sync Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:42:29 volumio2 sudo[2650]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 19 09:42:29 volumio2 sudo[2650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:29 volumio2 volumio[2515]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 19 09:42:29 volumio2 sudo[2655]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 19 09:42:29 volumio2 volumio[2515]: info: ___________ START PLUGINS ___________ Jun 19 09:42:29 volumio2 sudo[2655]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:29 volumio2 volumio[2515]: info: ControllerMpd::onStart: Initializing MPD Jun 19 09:42:29 volumio2 volumio[2515]: info: Creating MPD Configuration file Jun 19 09:42:29 volumio2 sudo[2650]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 09:42:29 volumio2 volumio[2515]: info: [1750318949592] CoreMusicLibrary::Adding element Medienserver Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 09:42:29 volumio2 systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:42:29 volumio2 sudo[2663]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 19 09:42:29 volumio2 sudo[2665]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 19 09:42:29 volumio2 sudo[2665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:29 volumio2 sudo[2663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:29 volumio2 volumio[2515]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 19 09:42:29 volumio2 sudo[2663]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:42:29 volumio2 systemd[1]: mpd.service: Deactivated successfully. Jun 19 09:42:29 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 19 09:42:29 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Jun 19 09:42:29 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 19 09:42:29 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 19 09:42:29 volumio2 volumio[2515]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 09:42:29 volumio2 volumio[2515]: info: [1750318949628] CoreMusicLibrary::Adding element Last_100 Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 09:42:29 volumio2 volumio[2515]: info: [1750318949629] CoreMusicLibrary::Adding element Webradio Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 09:42:29 volumio2 volumio[2515]: info: Initializing BBC Radios Jun 19 09:42:29 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 19 09:42:29 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: Creating Spotify config file Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: [now-playing] ConfigUpdater: config is up to date. Jun 19 09:42:29 volumio2 volumio[2515]: info: Loading i18n strings for locale de Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: FusionDsp - mixtype--------------------- None Jun 19 09:42:29 volumio2 volumio[2515]: info: Volumio Calling Home Jun 19 09:42:29 volumio2 sudo[2687]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 19 09:42:29 volumio2 volumio[2515]: info: Preparing to generate the ALSA configuration file Jun 19 09:42:29 volumio2 sudo[2687]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 19 09:42:29 volumio2 sudo[2687]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:29 volumio2 volumio[2515]: info: [now-playing] App is listening on port 4004. Jun 19 09:42:29 volumio2 volumio[2515]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jun 19 09:42:29 volumio2 volumio[2515]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 19 09:42:29 volumio2 volumio[2515]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 19 09:42:29 volumio2 volumio[2515]: info: Reading ALSA contributions from plugins. Jun 19 09:42:29 volumio2 volumio[2515]: info: Discovery: Connected to remote: 192.168.1.11 Jun 19 09:42:29 volumio2 volumio[2515]: info: MPD Permissions set Jun 19 09:42:29 volumio2 volumio[2515]: info: MPD Permissions set Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 19 09:42:29 volumio2 volumio[2515]: info: Volumio called home Jun 19 09:42:29 volumio2 volumio[2515]: info: Spotify config file written Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 sudo[2698]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 19 09:42:29 volumio2 sudo[2698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 systemd[1]: go-librespot-daemon.service: Killing process 1953 (go-librespot) with signal SIGKILL. Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jun 19 09:42:29 volumio2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:29 volumio2 volumio[2515]: info: No need to fix Spotify hosts Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 19 09:42:29 volumio2 go-librespot[2710]: go-librespot daemon starting... Jun 19 09:42:29 volumio2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 19 09:42:29 volumio2 sudo[2698]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:29 volumio2 go-librespot[2717]: time="2025-06-19T09:42:29+02:00" level=info msg="running go-librespot 0.2.0" Jun 19 09:42:29 volumio2 go-librespot[2717]: time="2025-06-19T09:42:29+02:00" level=debug msg="app state loaded" Jun 19 09:42:29 volumio2 go-librespot[2717]: time="2025-06-19T09:42:29+02:00" level=debug msg="stored credentials not found" Jun 19 09:42:29 volumio2 go-librespot[2717]: time="2025-06-19T09:42:29+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::volumioGetVisibleSources Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:29 volumio2 volumio[2515]: info: CorePlayQueue::getTrack 0 Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:29 volumio2 volumio[2515]: info: CorePlayQueue::getTrack 0 Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 19 09:42:29 volumio2 volumio[2515]: info: Received Get System Info Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 09:42:29 volumio2 volumio[2515]: info: Discovery: Getting this device information Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:29 volumio2 volumio[2515]: info: CorePlayQueue::getTrack 0 Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:29 volumio2 volumio[2515]: info: CorePlayQueue::getTrack 0 Jun 19 09:42:29 volumio2 volumio[2515]: info: Listing playlists Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:29 volumio2 volumio[2515]: info: CorePlayQueue::getTrack 0 Jun 19 09:42:29 volumio2 volumio[2515]: info: Starting Shairport Sync Jun 19 09:42:29 volumio2 volumio[2515]: info: Starting Shairport Sync Jun 19 09:42:29 volumio2 volumio[2515]: info: Starting Shairport Sync Jun 19 09:42:29 volumio2 sudo[2725]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 19 09:42:29 volumio2 sudo[2725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 09:42:29 volumio2 volumio[2515]: info: Discovery: Getting this device information Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:29 volumio2 volumio[2515]: info: CorePlayQueue::getTrack 0 Jun 19 09:42:29 volumio2 sudo[2727]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 19 09:42:29 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 09:42:29 volumio2 sudo[2727]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:30 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 19 09:42:30 volumio2 sudo[2730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 19 09:42:30 volumio2 sudo[2730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:30 volumio2 volumio[2515]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 19 09:42:30 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 19 09:42:30 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 19 09:42:30 volumio2 systemd[1]: shairport-sync.service: Consumed 1.931s CPU time. Jun 19 09:42:30 volumio2 volumio[2515]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 19 09:42:30 volumio2 go-librespot[2717]: time="2025-06-19T09:42:30+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jun 19 09:42:30 volumio2 go-librespot[2717]: time="2025-06-19T09:42:30+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jun 19 09:42:30 volumio2 go-librespot[2717]: time="2025-06-19T09:42:30+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jun 19 09:42:30 volumio2 go-librespot[2717]: time="2025-06-19T09:42:30+02:00" level=info msg="zeroconf server listening on port 39785" Jun 19 09:42:30 volumio2 volumio[2515]: info: Asound.conf file unchanged, so no further update is needed Jun 19 09:42:30 volumio2 volumio[2515]: info: Output device has changed, restarting MPD Jun 19 09:42:30 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 19 09:42:30 volumio2 volumio[2515]: info: Output device has changed, restarting Shairport Sync Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:42:30 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 19 09:42:30 volumio2 sudo[2725]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:30 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 19 09:42:30 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 19 09:42:30 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 19 09:42:30 volumio2 sudo[2727]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:30 volumio2 sudo[2736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 19 09:42:30 volumio2 sudo[2730]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:30 volumio2 sudo[2736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:30 volumio2 sudo[2736]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:30 volumio2 volumio[2515]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 19 09:42:30 volumio2 sudo[2737]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 19 09:42:30 volumio2 sudo[2737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:30 volumio2 systemd[1]: mpd.service: Deactivated successfully. Jun 19 09:42:30 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 19 09:42:30 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Jun 19 09:42:30 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 19 09:42:30 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 19 09:42:30 volumio2 volumio[2515]: info: MPD Permissions set Jun 19 09:42:30 volumio2 volumio[2515]: info: Shairport-Sync Started Jun 19 09:42:30 volumio2 volumio[2515]: Error adding Membership: Error: addMembership EINVAL Jun 19 09:42:30 volumio2 volumio[2515]: info: Shairport-Sync Started Jun 19 09:42:30 volumio2 volumio[2515]: info: Shairport-Sync Started Jun 19 09:42:30 volumio2 volumio[2515]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.159 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jun 19 09:42:30 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:30 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:30 volumio2 volumio[2515]: info: CorePlayQueue::getTrack 0 Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 19 09:42:30 volumio2 sudo[2762]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 19 09:42:30 volumio2 sudo[2762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 19 09:42:30 volumio2 sudo[2762]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 09:42:30 volumio2 volumio[2515]: info: Starting Shairport Sync Jun 19 09:42:30 volumio2 volumio[2515]: verbose: New Socket.io Connection to 192.168.1.215 from 192.168.1.159 UA: Mozilla/5.0 (Linux; Android 15; SM-S921B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jun 19 09:42:30 volumio2 sudo[2775]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 19 09:42:30 volumio2 sudo[2775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:30 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 19 09:42:30 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 19 09:42:30 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 19 09:42:30 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 19 09:42:30 volumio2 sudo[2775]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::volumioGetState Jun 19 09:42:30 volumio2 volumio[2515]: info: CorePlayQueue::getTrack 0 Jun 19 09:42:30 volumio2 volumio[2515]: info: Shairport-Sync Started Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 19 09:42:30 volumio2 volumio[2515]: Searching all installed plugins Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: , search Jun 19 09:42:30 volumio2 volumio[2515]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jun 19 09:42:30 volumio2 volumio[2515]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 09:42:30 volumio2 volumio[2515]: TypeError: Cannot read properties of undefined (reading 'then') Jun 19 09:42:30 volumio2 volumio[2515]: at ControllerMpd.search (/volumio/app/plugins/music_service/mpd/index.js:1583:17) Jun 19 09:42:30 volumio2 volumio[2515]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Jun 19 09:42:30 volumio2 volumio[2515]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Jun 19 09:42:30 volumio2 volumio[2515]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Jun 19 09:42:30 volumio2 volumio[2515]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Jun 19 09:42:30 volumio2 volumio[2515]: at Socket.emit (node:events:514:28) Jun 19 09:42:30 volumio2 volumio[2515]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jun 19 09:42:30 volumio2 volumio[2515]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jun 19 09:42:30 volumio2 volumio[2515]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 09:42:30 volumio2 sudo[2806]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-19 09:41' Jun 19 09:42:30 volumio2 sudo[2806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 19 09:42:30 volumio2 mpd[2772]: 2025-06-19T09:42:30 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 19 09:42:30 volumio2 systemd[1]: Started mpd.service - Music Player Daemon. Jun 19 09:42:30 volumio2 sudo[2655]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:30 volumio2 sudo[2665]: pam_unix(sudo:session): session closed for user root Jun 19 09:42:30 volumio2 sudo[2737]: pam_unix(sudo:session): session closed for user root PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cee9bb5b0ee50e4898478097a64b5c6c77d5a212" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 14 07:01:29 UTC 2025" VOLUMIO_VERSION="4.012" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="38c4f0e007cb9b6482e9f27bbe65b0b5"