-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-09-14 18:42:32 CEST. -- Sep 14 18:41:46 volumioraspberrry systemd-timedated[1195]: Changed local time to Sun Sep 14 18:41:46 2025 Sep 14 18:41:46 volumioraspberrry systemd[1]: Starting Daily man-db regeneration... Sep 14 18:41:46 volumioraspberrry systemd[1]: Starting Daily apt download activities... Sep 14 18:41:46 volumioraspberrry sudo[1193]: pam_unix(sudo:session): session closed for user root Sep 14 18:41:46 volumioraspberrry volumio-time-update[895]: volumio-time-update-util: System time updated successfully. Sep 14 18:41:46 volumioraspberrry systemd[1]: Started Volumio Time Update Utility. Sep 14 18:41:46 volumioraspberrry mandb[1200]: /usr/bin/mandb: warning: no MANDB_MAP directives in /etc/manpath.config, using your manpath Sep 14 18:41:46 volumioraspberrry systemd[1]: man-db.service: Succeeded. Sep 14 18:41:46 volumioraspberrry systemd[1]: Started Daily man-db regeneration. Sep 14 18:41:46 volumioraspberrry wpa_supplicant[1102]: wlan0: Trying to associate with SSID 'Deco' Sep 14 18:41:46 volumioraspberrry wireless.js[898]: trying... Sep 14 18:41:46 volumioraspberrry sudo[1230]: volumio : unable to resolve host volumioraspberrry Sep 14 18:41:46 volumioraspberrry sudo[1230]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:41:46 volumioraspberrry sudo[1230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 18:41:46 volumioraspberrry sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:41:46 volumioraspberrry sudo[1230]: pam_unix(sudo:session): session closed for user root Sep 14 18:41:46 volumioraspberrry wireless.js[898]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:41:46 volumioraspberrry nmbd[984]: [2025/09/14 18:41:46.346856, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 14 18:41:46 volumioraspberrry nmbd[984]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Sep 14 18:41:46 volumioraspberrry systemd[1]: Started Samba NMB Daemon. Sep 14 18:41:46 volumioraspberrry dhcpcd[1089]: eth0: leased 192.168.68.62 for 7200 seconds Sep 14 18:41:46 volumioraspberrry dhcpcd[1089]: eth0: adding route to 192.168.68.0/22 Sep 14 18:41:46 volumioraspberrry dhcpcd[1089]: eth0: adding default route via 192.168.68.1 Sep 14 18:41:46 volumioraspberrry dhcpcd[1089]: forked to background, child pid 1255 Sep 14 18:41:46 volumioraspberrry systemd[1]: Starting Samba Winbind Daemon... Sep 14 18:41:46 volumioraspberrry sudo[1088]: pam_unix(sudo:session): session closed for user root Sep 14 18:41:46 volumioraspberrry winbindd[1233]: [2025/09/14 18:41:46.471832, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Sep 14 18:41:46 volumioraspberrry winbindd[1233]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 14 18:41:46 volumioraspberrry winbindd[1233]: [2025/09/14 18:41:46.488417, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 14 18:41:46 volumioraspberrry winbindd[1233]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 14 18:41:46 volumioraspberrry systemd[1]: Started Samba Winbind Daemon. Sep 14 18:41:46 volumioraspberrry winbindd[1257]: [2025/09/14 18:41:46.490047, 0] ../lib/util/debug.c:1063(reopen_logs_internal) Sep 14 18:41:46 volumioraspberrry winbindd[1257]: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO/RASPBERRRY': No such file or directory Sep 14 18:41:46 volumioraspberrry systemd[1]: Starting Samba SMB Daemon... Sep 14 18:41:46 volumioraspberrry smbd[1259]: [2025/09/14 18:41:46.751826, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 14 18:41:46 volumioraspberrry systemd[1]: Started Samba SMB Daemon. Sep 14 18:41:46 volumioraspberrry smbd[1259]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 14 18:41:47 volumioraspberrry wireless.js[898]: trying... Sep 14 18:41:47 volumioraspberrry sudo[1268]: volumio : unable to resolve host volumioraspberrry Sep 14 18:41:47 volumioraspberrry sudo[1268]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:41:47 volumioraspberrry sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 18:41:47 volumioraspberrry sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:41:47 volumioraspberrry sudo[1268]: pam_unix(sudo:session): session closed for user root Sep 14 18:41:47 volumioraspberrry wireless.js[898]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:41:47 volumioraspberrry ntpd[1010]: Listen normally on 3 eth0 192.168.68.62:123 Sep 14 18:41:47 volumioraspberrry ntpd[1010]: new interface(s) found: waking up resolver Sep 14 18:41:48 volumioraspberrry volumio-remote-updater[906]: [2025-09-14 18:41:48] [info] asio async_connect error: system:111 (Connection refused) Sep 14 18:41:48 volumioraspberrry volumio-remote-updater[906]: [2025-09-14 18:41:48] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 14 18:41:48 volumioraspberrry volumio-remote-updater[906]: [2025-09-14 18:41:48] [error] handle_connect error: Underlying Transport Error Sep 14 18:41:48 volumioraspberrry wireless.js[898]: trying... Sep 14 18:41:48 volumioraspberrry sudo[1288]: volumio : unable to resolve host volumioraspberrry Sep 14 18:41:48 volumioraspberrry sudo[1288]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:41:48 volumioraspberrry sudo[1288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 18:41:48 volumioraspberrry sudo[1288]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:41:48 volumioraspberrry sudo[1288]: pam_unix(sudo:session): session closed for user root Sep 14 18:41:48 volumioraspberrry wireless.js[898]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:41:48 volumioraspberrry systemd[1]: apt-daily.service: Succeeded. Sep 14 18:41:48 volumioraspberrry systemd[1]: Started Daily apt download activities. Sep 14 18:41:48 volumioraspberrry systemd[1]: Starting Daily apt upgrade and clean activities... Sep 14 18:41:48 volumioraspberrry systemd[1]: apt-daily-upgrade.service: Succeeded. Sep 14 18:41:48 volumioraspberrry systemd[1]: Started Daily apt upgrade and clean activities. Sep 14 18:41:49 volumioraspberrry wireless.js[898]: trying... Sep 14 18:41:49 volumioraspberrry sudo[1352]: volumio : unable to resolve host volumioraspberrry Sep 14 18:41:49 volumioraspberrry sudo[1352]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:41:49 volumioraspberrry sudo[1352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 18:41:49 volumioraspberrry sudo[1352]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:41:49 volumioraspberrry sudo[1352]: pam_unix(sudo:session): session closed for user root Sep 14 18:41:49 volumioraspberrry wireless.js[898]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:41:49 volumioraspberrry wpa_supplicant[1102]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=ec:75:0c:51:a3:11 status_code=16 Sep 14 18:41:50 volumioraspberrry wireless.js[898]: trying... Sep 14 18:41:50 volumioraspberrry sudo[1355]: volumio : unable to resolve host volumioraspberrry Sep 14 18:41:50 volumioraspberrry sudo[1355]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:41:50 volumioraspberrry sudo[1355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 18:41:50 volumioraspberrry sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:41:50 volumioraspberrry sudo[1355]: pam_unix(sudo:session): session closed for user root Sep 14 18:41:50 volumioraspberrry wireless.js[898]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:41:51 volumioraspberrry wireless.js[898]: trying... Sep 14 18:41:51 volumioraspberrry sudo[1358]: volumio : unable to resolve host volumioraspberrry Sep 14 18:41:51 volumioraspberrry sudo[1358]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:41:51 volumioraspberrry sudo[1358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 18:41:51 volumioraspberrry sudo[1358]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:41:51 volumioraspberrry sudo[1358]: pam_unix(sudo:session): session closed for user root Sep 14 18:41:51 volumioraspberrry wireless.js[898]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:41:52 volumioraspberrry wireless.js[898]: trying... Sep 14 18:41:52 volumioraspberrry sudo[1361]: volumio : unable to resolve host volumioraspberrry Sep 14 18:41:52 volumioraspberrry sudo[1361]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:41:52 volumioraspberrry sudo[1361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 18:41:52 volumioraspberrry sudo[1361]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:41:52 volumioraspberrry sudo[1361]: pam_unix(sudo:session): session closed for user root Sep 14 18:41:52 volumioraspberrry wireless.js[898]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:41:53 volumioraspberrry wireless.js[898]: trying... Sep 14 18:41:53 volumioraspberrry sudo[1364]: volumio : unable to resolve host volumioraspberrry Sep 14 18:41:53 volumioraspberrry sudo[1364]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:41:53 volumioraspberrry sudo[1364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 18:41:53 volumioraspberrry sudo[1364]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:41:53 volumioraspberrry sudo[1364]: pam_unix(sudo:session): session closed for user root Sep 14 18:41:53 volumioraspberrry wireless.js[898]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:41:54 volumioraspberrry wireless.js[898]: trying... Sep 14 18:41:54 volumioraspberrry sudo[1367]: volumio : unable to resolve host volumioraspberrry Sep 14 18:41:54 volumioraspberrry sudo[1367]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:41:54 volumioraspberrry sudo[1367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 18:41:54 volumioraspberrry sudo[1367]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:41:54 volumioraspberrry sudo[1367]: pam_unix(sudo:session): session closed for user root Sep 14 18:41:54 volumioraspberrry wireless.js[898]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:41:55 volumioraspberrry wireless.js[898]: trying... Sep 14 18:41:55 volumioraspberrry sudo[1370]: volumio : unable to resolve host volumioraspberrry Sep 14 18:41:55 volumioraspberrry sudo[1370]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:41:55 volumioraspberrry sudo[1370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 18:41:55 volumioraspberrry sudo[1370]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:41:55 volumioraspberrry sudo[1370]: pam_unix(sudo:session): session closed for user root Sep 14 18:41:55 volumioraspberrry wireless.js[898]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:41:56 volumioraspberrry wireless.js[898]: trying... Sep 14 18:41:56 volumioraspberrry wpa_supplicant[1102]: wlan0: Trying to associate with SSID 'Deco' Sep 14 18:41:56 volumioraspberrry sudo[1373]: volumio : unable to resolve host volumioraspberrry Sep 14 18:41:56 volumioraspberrry sudo[1373]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:41:56 volumioraspberrry sudo[1373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 18:41:56 volumioraspberrry sudo[1373]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:41:56 volumioraspberrry sudo[1373]: pam_unix(sudo:session): session closed for user root Sep 14 18:41:56 volumioraspberrry wireless.js[898]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:41:57 volumioraspberrry wireless.js[898]: trying... Sep 14 18:41:57 volumioraspberrry sudo[1379]: volumio : unable to resolve host volumioraspberrry Sep 14 18:41:57 volumioraspberrry sudo[1379]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:41:57 volumioraspberrry sudo[1379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 18:41:57 volumioraspberrry sudo[1379]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:41:57 volumioraspberrry sudo[1379]: pam_unix(sudo:session): session closed for user root Sep 14 18:41:57 volumioraspberrry wireless.js[898]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:41:58 volumioraspberrry volumio-remote-updater[906]: [2025-09-14 18:41:58] [info] asio async_connect error: system:111 (Connection refused) Sep 14 18:41:58 volumioraspberrry volumio-remote-updater[906]: [2025-09-14 18:41:58] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 14 18:41:58 volumioraspberrry volumio-remote-updater[906]: [2025-09-14 18:41:58] [error] handle_connect error: Underlying Transport Error Sep 14 18:41:58 volumioraspberrry wireless.js[898]: trying... Sep 14 18:41:58 volumioraspberrry sudo[1382]: volumio : unable to resolve host volumioraspberrry Sep 14 18:41:58 volumioraspberrry sudo[1382]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:41:58 volumioraspberrry sudo[1382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 18:41:58 volumioraspberrry sudo[1382]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:41:58 volumioraspberrry sudo[1382]: pam_unix(sudo:session): session closed for user root Sep 14 18:41:58 volumioraspberrry wireless.js[898]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:41:59 volumioraspberrry wireless.js[898]: trying... Sep 14 18:41:59 volumioraspberrry sudo[1385]: volumio : unable to resolve host volumioraspberrry Sep 14 18:41:59 volumioraspberrry sudo[1385]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:41:59 volumioraspberrry sudo[1385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 18:41:59 volumioraspberrry sudo[1385]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:41:59 volumioraspberrry sudo[1385]: pam_unix(sudo:session): session closed for user root Sep 14 18:41:59 volumioraspberrry wireless.js[898]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:41:59 volumioraspberrry wpa_supplicant[1102]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=ec:75:0c:51:ad:e4 status_code=16 Sep 14 18:42:00 volumioraspberrry wireless.js[898]: trying... Sep 14 18:42:00 volumioraspberrry sudo[1388]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:00 volumioraspberrry sudo[1388]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:00 volumioraspberrry sudo[1388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 14 18:42:00 volumioraspberrry sudo[1388]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:00 volumioraspberrry sudo[1388]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:00 volumioraspberrry wireless.js[898]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:42:01 volumioraspberrry wireless.js[898]: Overtime, starting plan B Sep 14 18:42:01 volumioraspberrry wireless.js[898]: STARTING HOTSPOT Sep 14 18:42:01 volumioraspberrry wpa_supplicant[1102]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Sep 14 18:42:01 volumioraspberrry wpa_supplicant[1102]: nl80211: Failed to set IPv4 unicast in multicast filter Sep 14 18:42:01 volumioraspberrry wpa_supplicant[1102]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Sep 14 18:42:01 volumioraspberrry wpa_supplicant[1102]: nl80211: Failed to set IPv4 unicast in multicast filter Sep 14 18:42:01 volumioraspberrry wpa_supplicant[1102]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 Sep 14 18:42:01 volumioraspberrry wpa_supplicant[1102]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING Sep 14 18:42:01 volumioraspberrry wpa_supplicant[1102]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 14 18:42:01 volumioraspberrry wpa_supplicant[1102]: wlan0: CTRL-EVENT-TERMINATING Sep 14 18:42:04 volumioraspberrry sudo[1397]: root : unable to resolve host volumioraspberrry Sep 14 18:42:04 volumioraspberrry sudo[1397]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:04 volumioraspberrry sudo[1397]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 14 18:42:04 volumioraspberrry sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:04 volumioraspberrry sudo[1397]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:04 volumioraspberrry sudo[1399]: root : unable to resolve host volumioraspberrry Sep 14 18:42:04 volumioraspberrry sudo[1399]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:04 volumioraspberrry sudo[1399]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 14 18:42:04 volumioraspberrry sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:04 volumioraspberrry sudo[1399]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:04 volumioraspberrry avahi-daemon[909]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Sep 14 18:42:04 volumioraspberrry avahi-daemon[909]: New relevant interface wlan0.IPv4 for mDNS. Sep 14 18:42:04 volumioraspberrry avahi-daemon[909]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Sep 14 18:42:04 volumioraspberrry kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 14 18:42:04 volumioraspberrry systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Sep 14 18:42:04 volumioraspberrry systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Sep 14 18:42:04 volumioraspberrry dnsmasq[1406]: dnsmasq: syntax check OK. Sep 14 18:42:04 volumioraspberrry hostapd[1405]: Configuration file: /etc/hostapd/hostapd.conf Sep 14 18:42:04 volumioraspberrry hostapd[1405]: wlan0: Could not connect to kernel driver Sep 14 18:42:04 volumioraspberrry hostapd[1405]: Using interface wlan0 with hwaddr 88:a2:9e:2f:bd:51 and ssid "Volumio-5D13C" Sep 14 18:42:04 volumioraspberrry dnsmasq[1412]: started, version 2.80 cachesize 150 Sep 14 18:42:04 volumioraspberrry dnsmasq[1412]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Sep 14 18:42:04 volumioraspberrry dnsmasq-dhcp[1412]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 14 18:42:04 volumioraspberrry dnsmasq[1412]: reading /etc/resolv.conf Sep 14 18:42:04 volumioraspberrry dnsmasq[1412]: using nameserver 192.168.1.1#53 Sep 14 18:42:04 volumioraspberrry dnsmasq[1412]: using nameserver 192.168.68.1#53 Sep 14 18:42:04 volumioraspberrry dnsmasq[1412]: using nameserver 208.67.222.222#53 Sep 14 18:42:04 volumioraspberrry dnsmasq[1412]: using nameserver 208.67.220.220#53 Sep 14 18:42:04 volumioraspberrry dnsmasq[1412]: read /etc/hosts - 1 addresses Sep 14 18:42:04 volumioraspberrry systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Sep 14 18:42:04 volumioraspberrry systemd[1]: Reached target Host and Network Name Lookups. Sep 14 18:42:04 volumioraspberrry dhcpcd[1255]: wlan0: carrier acquired Sep 14 18:42:04 volumioraspberrry hostapd[1405]: wlan0: interface state UNINITIALIZED->ENABLED Sep 14 18:42:04 volumioraspberrry hostapd[1405]: wlan0: AP-ENABLED Sep 14 18:42:04 volumioraspberrry systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 14 18:42:04 volumioraspberrry dhcpcd[1255]: wlan0: IAID 9e:2f:bd:51 Sep 14 18:42:04 volumioraspberrry dhcpcd[1255]: wlan0: adding address fe80::b40e:9510:e18d:1ea2 Sep 14 18:42:04 volumioraspberrry dhcpcd[1255]: ipv6_addaddr1: Permission denied Sep 14 18:42:04 volumioraspberrry iwconfig[1421]: Error for wireless request "Set Power Management" (8B2C) : Sep 14 18:42:04 volumioraspberrry iwconfig[1421]: SET failed on device wlan0 ; Invalid argument. Sep 14 18:42:04 volumioraspberrry systemd[1]: Started Wireless Services. Sep 14 18:42:04 volumioraspberrry systemd[1]: Started Volumio Backend Module. Sep 14 18:42:04 volumioraspberrry systemd[1]: Started Volumio Cpu Tweaker. Sep 14 18:42:04 volumioraspberrry systemd[1]: Reached target Multi-User System. Sep 14 18:42:04 volumioraspberrry systemd[1]: Reached target Graphical Interface. Sep 14 18:42:04 volumioraspberrry systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 14 18:42:04 volumioraspberrry systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 14 18:42:04 volumioraspberrry systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 14 18:42:04 volumioraspberrry dhcpcd[1255]: wlan0: soliciting a DHCP lease Sep 14 18:42:04 volumioraspberrry systemd[1]: Startup finished in 14.428s (kernel) + 30.575s (userspace) = 45.003s. Sep 14 18:42:04 volumioraspberrry volumio-cpu-tweak[1423]: Setting RT Priority for mpd Sep 14 18:42:04 volumioraspberrry volumio-cpu-tweak[1423]: Setting MPD Affinity Sep 14 18:42:04 volumioraspberrry volumio-cpu-tweak[1423]: pid 1035's current affinity mask: f Sep 14 18:42:04 volumioraspberrry volumio-cpu-tweak[1423]: pid 1035's new affinity mask: 3 Sep 14 18:42:04 volumioraspberrry volumio-cpu-tweak[1423]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Sep 14 18:42:04 volumioraspberrry volumio-cpu-tweak[1423]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Sep 14 18:42:04 volumioraspberrry systemd[1]: volumio_cpu_tweak.service: Succeeded. Sep 14 18:42:05 volumioraspberrry dhcpcd[1255]: wlan0: soliciting an IPv6 router Sep 14 18:42:05 volumioraspberrry systemd[1]: systemd-fsckd.service: Succeeded. Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: ------------------------------------------- Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: ----- Volumio3 ---- Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: ------------------------------------------- Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: ----- System startup ---- Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: ------------------------------------------- Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: MYVOLUMIO Environment detected Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Plugin folders cleanup Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Scanning into folder /volumio/app/plugins/ Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Scanning category audio_interface Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Scanning category miscellanea Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Scanning category music_service Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Scanning category plugins.json Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Scanning category system_controller Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Scanning category user_interface Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Scanning into folder /data/plugins/ Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Scanning category audio_interface Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Scanning category system_controller Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Scanning category user_interface Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Plugin folders cleanup completed Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: ------------------------------------------- Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: ----- Core plugins startup ---- Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: ------------------------------------------- Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Loading plugins from folder /volumio/app/plugins/ Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Adding plugin upnp to MyMusic Plugins Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Loading plugins from folder /data/plugins/ Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Loading plugin "system"... Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Loading plugin "ampswitch"... Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Applying required configuration parameters for plugin ampswitch Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Loading plugin "appearance"... Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Loading plugin "network"... Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Refreshing Cached IP Addresses Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Loading plugin "services"... Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Loading plugin "alsa_controller"... Sep 14 18:42:05 volumioraspberrry sudo[1455]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:05 volumioraspberrry sudo[1455]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:05 volumioraspberrry sudo[1455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 18:42:05 volumioraspberrry sudo[1455]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:05 volumioraspberrry sudo[1457]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:05 volumioraspberrry sudo[1457]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:05 volumioraspberrry sudo[1455]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:05 volumioraspberrry sudo[1457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 18:42:05 volumioraspberrry sudo[1457]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:05 volumioraspberrry sudo[1457]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:05 volumioraspberrry sudo[1468]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:05 volumioraspberrry sudo[1468]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:05 volumioraspberrry sudo[1468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 14 18:42:05 volumioraspberrry sudo[1468]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Loading plugin "wizard"... Sep 14 18:42:05 volumioraspberrry sudo[1468]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:05 volumioraspberrry kernel: ieee80211 phy0: brcmf_vif_set_mgmt_ie: vndr ie set error : -52 Sep 14 18:42:05 volumioraspberrry kernel: ieee80211 phy0: brcmf_cfg80211_scan: scan error (-52) Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Loading plugin "networkfs"... Sep 14 18:42:05 volumioraspberrry ntpd[1010]: Listen normally on 4 wlan0 192.168.211.1:123 Sep 14 18:42:05 volumioraspberrry ntpd[1010]: new interface(s) found: waking up resolver Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Starting Udev Watcher for removable devices Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Ignoring mount for partition: boot Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Ignoring mount for partition: volumio Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Ignoring mount for partition: volumio_data Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Loading plugin "volumio_command_line_client"... Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Loading plugin "upnp"... Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: [1757868125962] Starting Upmpd Daemon Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Loading plugin "my_music"... Sep 14 18:42:05 volumioraspberrry volumio[1422]: info: Loading plugin "mpd"... Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Loading plugin "upnp_browser"... Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Loading plugin "alarm-clock"... Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Loading plugin "airplay_emulation"... Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Starting Shairport Sync Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Loading plugin "last_100"... Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Loading plugin "webradio"... Sep 14 18:42:06 volumioraspberrry systemd[1]: systemd-hostnamed.service: Succeeded. Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Loading plugin "i2s_dacs"... Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Loading plugin "volumiodiscovery"... Sep 14 18:42:06 volumioraspberrry volumio[1422]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 14 18:42:06 volumioraspberrry node[1422]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 14 18:42:06 volumioraspberrry volumio[1422]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 18:42:06 volumioraspberrry volumio[1422]: *** WARNING *** For more information see Sep 14 18:42:06 volumioraspberrry volumio[1422]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 14 18:42:06 volumioraspberrry volumio[1422]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 18:42:06 volumioraspberrry volumio[1422]: *** WARNING *** For more information see Sep 14 18:42:06 volumioraspberrry node[1422]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 18:42:06 volumioraspberrry node[1422]: *** WARNING *** For more information see Sep 14 18:42:06 volumioraspberrry node[1422]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 14 18:42:06 volumioraspberrry node[1422]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 18:42:06 volumioraspberrry node[1422]: *** WARNING *** For more information see Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Discovery: Started advertising with name: Volumio/Raspberrry Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Loading plugin "now_playing"... Sep 14 18:42:06 volumioraspberrry login[1028]: pam_unix(login:session): session opened for user volumio by LOGIN(uid=0) Sep 14 18:42:06 volumioraspberrry systemd[1]: Created slice User Slice of UID 1000. Sep 14 18:42:06 volumioraspberrry systemd[1]: Starting User Runtime Directory /run/user/1000... Sep 14 18:42:06 volumioraspberrry systemd-logind[917]: New session 1 of user volumio. Sep 14 18:42:06 volumioraspberrry systemd[1]: Started User Runtime Directory /run/user/1000. Sep 14 18:42:06 volumioraspberrry systemd[1]: Starting User Manager for UID 1000... Sep 14 18:42:06 volumioraspberrry systemd[1494]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Sep 14 18:42:06 volumioraspberrry systemd[1494]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Sep 14 18:42:06 volumioraspberrry systemd[1494]: Listening on GnuPG cryptographic agent and passphrase cache. Sep 14 18:42:06 volumioraspberrry systemd[1494]: Listening on Sound System. Sep 14 18:42:06 volumioraspberrry systemd[1494]: Listening on GnuPG network certificate management daemon. Sep 14 18:42:06 volumioraspberrry systemd[1494]: Reached target Paths. Sep 14 18:42:06 volumioraspberrry systemd[1494]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Sep 14 18:42:06 volumioraspberrry systemd[1494]: Starting D-Bus User Message Bus Socket. Sep 14 18:42:06 volumioraspberrry systemd[1494]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Sep 14 18:42:06 volumioraspberrry systemd[1494]: Reached target Timers. Sep 14 18:42:06 volumioraspberrry systemd[1494]: Listening on D-Bus User Message Bus Socket. Sep 14 18:42:06 volumioraspberrry systemd[1494]: Reached target Sockets. Sep 14 18:42:06 volumioraspberrry systemd[1494]: Reached target Basic System. Sep 14 18:42:06 volumioraspberrry systemd[1494]: Reached target Default. Sep 14 18:42:06 volumioraspberrry systemd[1494]: Startup finished in 105ms. Sep 14 18:42:06 volumioraspberrry systemd[1]: Started User Manager for UID 1000. Sep 14 18:42:06 volumioraspberrry systemd[1]: Started Session 1 of user volumio. Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Loading plugin "outputs"... Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Loading plugin "albumart"... Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Plugin example_plugin is not enabled Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Loading plugin "inputs"... Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Loading plugin "updater_comm"... Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Plugin mpdemulation is not enabled Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Loading plugin "rest_api"... Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Loading plugin "websocket"... Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Starting Socket.io Server version 2.3.0 Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Plugin fusiondsp is not enabled Sep 14 18:42:06 volumioraspberrry volumio[1422]: info: Loading plugin "ferrum_streaming_control_technology"... Sep 14 18:42:06 volumioraspberrry volumio[1422]: Forking 3 albumart workers Sep 14 18:42:07 volumioraspberrry volumio[1422]: Starting albumart workers Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: Applying required configuration parameters for plugin ferrum_streaming_control_technology Sep 14 18:42:07 volumioraspberrry volumio[1422]: Starting albumart workers Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: Loading i18n strings for locale de Sep 14 18:42:07 volumioraspberrry volumio[1422]: Updating browse sources language Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 18:42:07 volumioraspberrry volumio[1422]: Starting albumart workers Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: CoreCommandRouter::initPlayerControls Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 18:42:07 volumioraspberrry volumio[1422]: Express server listening on port 3000 Sep 14 18:42:07 volumioraspberrry volumio[1422]: [Metrics] WebUI: 2s 271.98ms Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: CoreStateMachine::resetVolumioState Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: CoreStateMachine::getcurrentVolume Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: CoreStateMachine::pushState Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioPushState Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: Cannot read play queue from file Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: Volumio Network Manager: Network status updated: 1 Sep 14 18:42:07 volumioraspberrry volumio[1422]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 14 18:42:07 volumioraspberrry volumio[1422]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:42:07 volumioraspberrry volumio[1422]: wlan0 Interface doesn't support scanning : Invalid exchange Sep 14 18:42:07 volumioraspberrry volumio[1422]: info: Cannot use regular scanning, forcing with ap-force Sep 14 18:42:07 volumioraspberrry sudo[1570]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:07 volumioraspberrry sudo[1570]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:07 volumioraspberrry sudo[1570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 14 18:42:07 volumioraspberrry sudo[1570]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:08 volumioraspberrry volumio-remote-updater[906]: [2025-09-14 18:42:08] [connect] Successful connection Sep 14 18:42:09 volumioraspberrry dhcpcd[1255]: wlan0: probing for an IPv4LL address Sep 14 18:42:10 volumioraspberrry sudo[1570]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:10 volumioraspberrry volumio[1422]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:42:10 volumioraspberrry volumio[1422]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 14 18:42:10 volumioraspberrry volumio-remote-updater[906]: [2025-09-14 18:42:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757868128 101 Sep 14 18:42:10 volumioraspberrry volumio[1422]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Sep 14 18:42:10 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:10 volumioraspberrry volumio[1422]: info: Setting Device type: Raspberry PI Sep 14 18:42:10 volumioraspberrry volumio[1422]: info: CoreStateMachine::setRepeat null single undefined Sep 14 18:42:10 volumioraspberrry volumio[1422]: info: CoreStateMachine::pushState Sep 14 18:42:10 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:10 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioPushState Sep 14 18:42:10 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:10 volumioraspberrry volumio[1422]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 14 18:42:10 volumioraspberrry volumio[1422]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04171 Sep 14 18:42:10 volumioraspberrry volumio[1422]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 14 18:42:10 volumioraspberrry volumio[1422]: info: Completed loading Core Plugins Sep 14 18:42:10 volumioraspberrry volumio[1422]: info: Preparing to generate the ALSA configuration file Sep 14 18:42:10 volumioraspberrry volumio[1422]: info: Asound.conf file written Sep 14 18:42:10 volumioraspberrry sudo[1579]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:10 volumioraspberrry sudo[1579]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:10 volumioraspberrry sudo[1579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 14 18:42:10 volumioraspberrry sudo[1579]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:10 volumioraspberrry sudo[1579]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:10 volumioraspberrry volumio[1422]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:42:11 volumioraspberrry volumio[1422]: No state is present for card sndrpihifiberry Sep 14 18:42:11 volumioraspberrry volumio[1422]: Found hardware: "RPi-simple" "" "" "" "" Sep 14 18:42:11 volumioraspberrry volumio[1422]: Hardware is initialized using a generic method Sep 14 18:42:11 volumioraspberrry volumio[1422]: No state is present for card sndrpihifiberry Sep 14 18:42:11 volumioraspberrry volumio[1422]: No state is present for card vc4hdmi0 Sep 14 18:42:11 volumioraspberrry volumio[1422]: Found hardware: "vc4-hdmi" "" "" "" "" Sep 14 18:42:11 volumioraspberrry volumio[1422]: Hardware is initialized using a generic method Sep 14 18:42:11 volumioraspberrry volumio[1422]: No state is present for card vc4hdmi0 Sep 14 18:42:11 volumioraspberrry volumio[1422]: No state is present for card vc4hdmi1 Sep 14 18:42:11 volumioraspberrry volumio[1422]: Found hardware: "vc4-hdmi" "" "" "" "" Sep 14 18:42:11 volumioraspberrry volumio[1422]: Hardware is initialized using a generic method Sep 14 18:42:11 volumioraspberrry volumio[1422]: No state is present for card vc4hdmi1 Sep 14 18:42:11 volumioraspberrry volumio[1422]: No state is present for card HT Sep 14 18:42:11 volumioraspberrry volumio[1422]: Found hardware: "USB-Audio" "USB Mixer" "USB2752:004b" "" "" Sep 14 18:42:11 volumioraspberrry volumio[1422]: Hardware is initialized using a generic method Sep 14 18:42:11 volumioraspberrry volumio[1422]: No state is present for card HT Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: Output device has changed, restarting MPD Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: Output device has changed, restarting Shairport Sync Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: ___________ START PLUGINS ___________ Sep 14 18:42:11 volumioraspberrry sudo[1584]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:11 volumioraspberrry sudo[1584]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:11 volumioraspberrry sudo[1586]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:11 volumioraspberrry sudo[1586]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:11 volumioraspberrry sudo[1584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 18:42:11 volumioraspberrry sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:11 volumioraspberrry sudo[1586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 18:42:11 volumioraspberrry sudo[1586]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:11 volumioraspberrry sudo[1584]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:11 volumioraspberrry systemd[1]: Stopping Music Player Daemon... Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: ControllerMpd::onStart: Initializing MPD Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: Creating MPD Configuration file Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: [1757868131062] CoreMusicLibrary::Adding element Medienserver Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:11 volumioraspberrry sudo[1605]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:11 volumioraspberrry sudo[1605]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:11 volumioraspberrry sudo[1605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 18:42:11 volumioraspberrry sudo[1605]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:11 volumioraspberrry sudo[1607]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:11 volumioraspberrry sudo[1605]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:11 volumioraspberrry sudo[1607]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:11 volumioraspberrry sudo[1607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 18:42:11 volumioraspberrry sudo[1607]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:11 volumioraspberrry systemd[1]: mpd.service: Succeeded. Sep 14 18:42:11 volumioraspberrry systemd[1]: Stopped Music Player Daemon. Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: [1757868131106] CoreMusicLibrary::Adding element Last_100 Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: [1757868131107] CoreMusicLibrary::Adding element Webradio Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: Initializing BBC Radios Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:11 volumioraspberrry systemd[1]: Starting Music Player Daemon... Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: [now-playing] ConfigUpdater: config is up to date. Sep 14 18:42:11 volumioraspberrry fsct-plugin[1422]: Failed to initialize device 1d6b:0002: BOS descriptor not available, unsupported usb version 2.00 Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: Volumio Calling Home Sep 14 18:42:11 volumioraspberrry fsct-plugin[1422]: Failed to initialize device 1d6b:0002: BOS descriptor not available, unsupported usb version 2.00 Sep 14 18:42:11 volumioraspberrry fsct-plugin[1422]: Failed to initialize device 05ac:1006: BOS descriptor not available, unsupported usb version 2.00 Sep 14 18:42:11 volumioraspberrry fsct-plugin[1422]: Failed to initialize device 2752:004b: BOS descriptor not available, unsupported usb version 2.00 Sep 14 18:42:11 volumioraspberrry fsct-plugin[1422]: Failed to initialize device 1d6b:0003: IO error -> Permission denied (os error 13) Sep 14 18:42:11 volumioraspberrry fsct-plugin[1422]: Failed to initialize device 1d6b:0003: IO error -> Permission denied (os error 13) Sep 14 18:42:11 volumioraspberrry fsct-plugin[1422]: Failed to initialize device 1a86:e5e3: BOS descriptor not available, unsupported usb version 0.01 Sep 14 18:42:11 volumioraspberrry fsct-plugin[1422]: Failed to initialize device 05ac:0221: BOS descriptor not available, unsupported usb version 2.00 Sep 14 18:42:11 volumioraspberrry sudo[1614]: root : unable to resolve host volumioraspberrry Sep 14 18:42:11 volumioraspberrry sudo[1614]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:11 volumioraspberrry sudo[1614]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:42:11 volumioraspberrry sudo[1614]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 18:42:11 volumioraspberrry sudo[1614]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:11 volumioraspberrry sudo[1614]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: [now-playing] App is listening on port 4004. Sep 14 18:42:11 volumioraspberrry volumio[1422]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: Discovery: adding 4ebaeff4-fdfd-4456-b74b-764de459045d Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: Discovery: Found device Volumio/Raspberrry Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: Discovery: this is already registered, 4ebaeff4-fdfd-4456-b74b-764de459045d Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: Discovery: Found device Volumio/Raspberrry Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: MPD Permissions set Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: MPD Permissions set Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: FSCT Started Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: [ASDebug] CurState: stop PrevState: na Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: [ASDebug] CurState: stop PrevState: na Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Sep 14 18:42:11 volumioraspberrry volumio[1422]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: Starting Shairport Sync Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: Starting Shairport Sync Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: Starting Shairport Sync Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:11 volumioraspberrry sudo[1642]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:11 volumioraspberrry sudo[1642]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:11 volumioraspberrry sudo[1642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 18:42:11 volumioraspberrry sudo[1642]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:11 volumioraspberrry sudo[1644]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:11 volumioraspberrry sudo[1644]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:11 volumioraspberrry sudo[1644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 18:42:11 volumioraspberrry sudo[1644]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:11 volumioraspberrry systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 18:42:11 volumioraspberrry sudo[1646]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:11 volumioraspberrry sudo[1646]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:11 volumioraspberrry sudo[1646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 18:42:11 volumioraspberrry sudo[1646]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:11 volumioraspberrry systemd[1]: shairport-sync.service: Succeeded. Sep 14 18:42:11 volumioraspberrry systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: Volumio called home Sep 14 18:42:11 volumioraspberrry sudo[1642]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:11 volumioraspberrry systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 18:42:11 volumioraspberrry sudo[1646]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: Shairport-Sync Started Sep 14 18:42:11 volumioraspberrry sudo[1644]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:11 volumioraspberrry volumio[1422]: Error adding Membership: Error: addMembership EINVAL Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: Shairport-Sync Started Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: Shairport-Sync Started Sep 14 18:42:11 volumioraspberrry mpd[1632]: Sep 14 18:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 18:42:11 volumioraspberrry systemd[1]: Started Music Player Daemon. Sep 14 18:42:11 volumioraspberrry sudo[1607]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:11 volumioraspberrry sudo[1586]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: Completed starting Core Plugins Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: ------------------------------------------- Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: ----- MyVolumio plugins startup ---- Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: ------------------------------------------- Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: MPD running with PID1632 Sep 14 18:42:11 volumioraspberrry volumio[1422]: ,establishing connection Sep 14 18:42:11 volumioraspberrry volumio[1422]: error: MPD error: The expression evaluated to a falsy value: Sep 14 18:42:11 volumioraspberrry volumio[1422]: assert.ok(self.idling) Sep 14 18:42:11 volumioraspberrry volumio[1422]: error: The expression evaluated to a falsy value: Sep 14 18:42:11 volumioraspberrry volumio[1422]: assert.ok(self.idling) Sep 14 18:42:11 volumioraspberrry volumio[1422]: error: MPD error: The expression evaluated to a falsy value: Sep 14 18:42:11 volumioraspberrry volumio[1422]: assert.ok(self.idling) Sep 14 18:42:11 volumioraspberrry volumio[1422]: error: The expression evaluated to a falsy value: Sep 14 18:42:11 volumioraspberrry volumio[1422]: assert.ok(self.idling) Sep 14 18:42:11 volumioraspberrry volumio[1422]: error: updateQueue error: null Sep 14 18:42:11 volumioraspberrry volumio[1422]: info: [ASDebug] Togle GPIO: OFF Sep 14 18:42:12 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 18:42:12 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 18:42:12 volumioraspberrry volumio[1422]: info: Discovery: Getting this device information Sep 14 18:42:12 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:12 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:12 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 18:42:12 volumioraspberrry volumio[1422]: verbose: New Socket.io Connection to 192.168.68.62:3000 from 192.168.68.56 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Sep 14 18:42:12 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:12 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:12 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 14 18:42:12 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 14 18:42:14 volumioraspberrry dhcpcd[1255]: wlan0: using IPv4LL address 169.254.32.120 Sep 14 18:42:14 volumioraspberrry avahi-daemon[909]: Registering new address record for 169.254.32.120 on wlan0.IPv4. Sep 14 18:42:14 volumioraspberrry dhcpcd[1255]: wlan0: adding route to 169.254.0.0/16 Sep 14 18:42:15 volumioraspberrry ntpd[1010]: Listen normally on 5 wlan0 169.254.32.120:123 Sep 14 18:42:15 volumioraspberrry ntpd[1010]: new interface(s) found: waking up resolver Sep 14 18:42:15 volumioraspberrry sudo[1691]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:15 volumioraspberrry sudo[1691]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:15 volumioraspberrry sudo[1691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 18:42:15 volumioraspberrry sudo[1691]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:15 volumioraspberrry sudo[1691]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:15 volumioraspberrry sudo[1693]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:15 volumioraspberrry sudo[1693]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:15 volumioraspberrry sudo[1693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 18:42:15 volumioraspberrry sudo[1693]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:15 volumioraspberrry sudo[1693]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:15 volumioraspberrry sudo[1695]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:15 volumioraspberrry sudo[1695]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:16 volumioraspberrry sudo[1695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 14 18:42:16 volumioraspberrry sudo[1695]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:16 volumioraspberrry systemd[1]: Started UPnP Renderer front-end to MPD. Sep 14 18:42:16 volumioraspberrry sudo[1695]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:16 volumioraspberrry volumio[1422]: info: Upmpdcli Daemon Started Sep 14 18:42:16 volumioraspberrry systemd[1]: systemd-timedated.service: Succeeded. Sep 14 18:42:16 volumioraspberrry volumio[1699]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 14 18:42:16 volumioraspberrry volumio[1422]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 14 18:42:16 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 18:42:16 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 18:42:16 volumioraspberrry volumio[1422]: info: Discovery: Getting this device information Sep 14 18:42:16 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:16 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:16 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 18:42:16 volumioraspberrry volumio[1422]: verbose: New Socket.io Connection to 192.168.68.62:3000 from 192.168.68.56 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Sep 14 18:42:16 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:16 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:16 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 14 18:42:16 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 14 18:42:17 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:17 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:18 volumioraspberrry sudo[1721]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:18 volumioraspberrry sudo[1721]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:18 volumioraspberrry sudo[1721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 18:42:18 volumioraspberrry sudo[1721]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:18 volumioraspberrry sudo[1723]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:18 volumioraspberrry sudo[1723]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:18 volumioraspberrry sudo[1723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 18:42:18 volumioraspberrry sudo[1723]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:18 volumioraspberrry sudo[1721]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:18 volumioraspberrry sudo[1723]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:18 volumioraspberrry volumio[1422]: verbose: New Socket.io Connection to 192.168.68.62 from 192.168.68.56 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Sep 14 18:42:18 volumioraspberrry sudo[1727]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:18 volumioraspberrry sudo[1727]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:18 volumioraspberrry sudo[1727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 18:42:18 volumioraspberrry sudo[1727]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:18 volumioraspberrry sudo[1729]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:18 volumioraspberrry sudo[1729]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:18 volumioraspberrry sudo[1729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 18:42:18 volumioraspberrry sudo[1729]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:18 volumioraspberrry sudo[1727]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:18 volumioraspberrry sudo[1729]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:18 volumioraspberrry volumio[1422]: verbose: New Socket.io Connection to 192.168.68.62 from 192.168.68.56 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Sep 14 18:42:18 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:18 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:18 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 14 18:42:18 volumioraspberrry volumio[1422]: info: Listing playlists Sep 14 18:42:18 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 14 18:42:18 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 14 18:42:18 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 14 18:42:18 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 14 18:42:19 volumioraspberrry volumio[1699]: ................................................................++++ Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: Discovery: Getting this device information Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: Adding plugin bluetooth to MyMusic Plugins Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: Adding plugin multiroom to MyMusic Plugins Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: Adding plugin metavolumio to MyMusic Plugins Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: Adding plugin cd_controller to MyMusic Plugins Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: Starting MyVolumio Remote Streaming Endpoints Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: MyVolumio login type: Token Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: Starting Streaming Service Transparent Proxy Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: Streaming services startup Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: Starting Streaming Daemon Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: Received Get System Info Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: Discovery: Getting this device information Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:20 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 18:42:20 volumioraspberrry sudo[1739]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:20 volumioraspberrry sudo[1739]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:20 volumioraspberrry sudo[1739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 14 18:42:20 volumioraspberrry sudo[1739]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:20 volumioraspberrry sudo[1739]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:20 volumioraspberrry volumio[1422]: error: Cannot start Volumio Streaming Daemon Sep 14 18:42:20 volumioraspberrry volumio[1422]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 14 18:42:20 volumioraspberrry volumio[1422]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:42:20 volumioraspberrry volumio[1422]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 14 18:42:21 volumioraspberrry volumio[1422]: STREAMING PROXY: Starting server on port 3245 Sep 14 18:42:21 volumioraspberrry volumio[1422]: Node JS runtime: 14 Sep 14 18:42:21 volumioraspberrry volumio[1422]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 14 18:42:21 volumioraspberrry volumio[1699]: ...................................................++++ Sep 14 18:42:21 volumioraspberrry volumio[1699]: e is 65537 (0x010001) Sep 14 18:42:21 volumioraspberrry volumio[1699]: writing RSA key Sep 14 18:42:22 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 14 18:42:22 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: Received Get System Info Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: Discovery: Getting this device information Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: MyVolumio token set successfully Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: MYVOLUMIO: Adding device Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: MYVOLUMIO: Evaluating Server Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: MyVolumio Plan changed: premium Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: Removing browser output: myVolumio user plan is not superstar Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: Removing audio output: Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: MYVOLUMIO: Adding device Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: MYVOLUMIO: Evaluating Server Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: Remote config written successfully Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: Starting Tunnel 1 Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: Starting Tunnel Connection Checker Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: Completed starting MyVolumio Plugin Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: MYVolumio Device enabled Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: MyVolumio status changed Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: Streaming services startup Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: Starting Streaming Daemon Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Sep 14 18:42:23 volumioraspberrry sudo[1794]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:23 volumioraspberrry sudo[1794]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:23 volumioraspberrry sudo[1794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 14 18:42:23 volumioraspberrry sudo[1794]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:23 volumioraspberrry sudo[1794]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: [1757868143848] Starting BluetoothController Sep 14 18:42:23 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Sep 14 18:42:24 volumioraspberrry sudo[1797]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1797]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry sudo[1797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Sep 14 18:42:24 volumioraspberrry sudo[1797]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry sudo[1797]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry volumio[1422]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: MRS: MultiRoom plugin initialized Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: MRS: STOPPING SNAPCLIENT Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: MRS: Snap server stop Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: MRS: STOPPING volumioStreaming Sep 14 18:42:24 volumioraspberrry sudo[1813]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1813]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Sep 14 18:42:24 volumioraspberrry sudo[1813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Sep 14 18:42:24 volumioraspberrry sudo[1813]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry sudo[1815]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1815]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry sudo[1815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Sep 14 18:42:24 volumioraspberrry sudo[1815]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Sep 14 18:42:24 volumioraspberrry sudo[1813]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry sudo[1817]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1817]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry sudo[1817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Sep 14 18:42:24 volumioraspberrry sudo[1817]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Sep 14 18:42:24 volumioraspberrry sudo[1819]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1819]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry sudo[1815]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry sudo[1819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Sep 14 18:42:24 volumioraspberrry sudo[1819]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Sep 14 18:42:24 volumioraspberrry sudo[1819]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry sudo[1817]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Preparing to generate the ALSA configuration file Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Setting Geolocation for MyVolumio to eu6 Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: MRS: Removed streaming files Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: MRS: volumioStreaming STOPPED Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: MRS: SNAPSERVER STOPPED Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: MRS: SNAPCLIENT STOPPED Sep 14 18:42:24 volumioraspberrry volumio[1422]: error: Cannot start Volumio Streaming Daemon Sep 14 18:42:24 volumioraspberrry volumio[1422]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 14 18:42:24 volumioraspberrry volumio[1422]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:42:24 volumioraspberrry volumio[1422]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Reading ALSA contributions from plugins. Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Asound.conf file written Sep 14 18:42:24 volumioraspberrry sudo[1825]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1825]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry sudo[1825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 14 18:42:24 volumioraspberrry sudo[1825]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry sudo[1825]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry volumio[1422]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card sndrpihifiberry Sep 14 18:42:24 volumioraspberrry volumio[1422]: Found hardware: "RPi-simple" "" "" "" "" Sep 14 18:42:24 volumioraspberrry volumio[1422]: Hardware is initialized using a generic method Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card sndrpihifiberry Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card vc4hdmi0 Sep 14 18:42:24 volumioraspberrry volumio[1422]: Found hardware: "vc4-hdmi" "" "" "" "" Sep 14 18:42:24 volumioraspberrry volumio[1422]: Hardware is initialized using a generic method Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card vc4hdmi0 Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card vc4hdmi1 Sep 14 18:42:24 volumioraspberrry volumio[1422]: Found hardware: "vc4-hdmi" "" "" "" "" Sep 14 18:42:24 volumioraspberrry volumio[1422]: Hardware is initialized using a generic method Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card vc4hdmi1 Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card HT Sep 14 18:42:24 volumioraspberrry volumio[1422]: Found hardware: "USB-Audio" "USB Mixer" "USB2752:004b" "" "" Sep 14 18:42:24 volumioraspberrry volumio[1422]: Hardware is initialized using a generic method Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card HT Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Output device has changed, restarting MPD Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Output device has changed, restarting Shairport Sync Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 18:42:24 volumioraspberrry sudo[1832]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1832]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry sudo[1832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 18:42:24 volumioraspberrry sudo[1830]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1830]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry sudo[1830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 18:42:24 volumioraspberrry sudo[1830]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry sudo[1830]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: MRS: Pushing multiroomSync output for this device Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: MRS: Pushing multiroomSync output Sep 14 18:42:24 volumioraspberrry systemd[1]: Stopping Music Player Daemon... Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Adding audio output: Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Adding audio output: Sep 14 18:42:24 volumioraspberrry systemd[1]: mpd.service: Succeeded. Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:24 volumioraspberrry systemd[1]: Stopped Music Player Daemon. Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Sep 14 18:42:24 volumioraspberrry sudo[1841]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Adding METAVOLUMIO REST API Endpoints Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Preparing CD Folders Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Adding CD REST API Endpoints Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Starting UDEV Watcher for CD Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Detecting CD presence with UDEV Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Adding inputs REST Endpoints Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Scanning Audio Inputs Sep 14 18:42:24 volumioraspberrry sudo[1841]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry systemd[1]: Starting Music Player Daemon... Sep 14 18:42:24 volumioraspberrry sudo[1841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio/Raspberrry Sep 14 18:42:24 volumioraspberrry sudo[1841]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry sudo[1841]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Checking against Known Cards name Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Adding Server instance for streaming Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Sep 14 18:42:24 volumioraspberrry volumio[1422]: error: Hi Res Audio Failed Login: Missing Login Data Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Adding HIGHRESAUDIO REST API Endpoints Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Refreshing TIDAL token Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: QobuzConnect: Starting Qobuz Connect socket and service Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Stopping AccessToken refresher cron for QOBUZ Sep 14 18:42:24 volumioraspberrry sudo[1850]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1850]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry sudo[1850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 14 18:42:24 volumioraspberrry sudo[1850]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: AccessToken refresher cron started for QOBUZ Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Adding QOBUZ REST API Endpoints Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Sep 14 18:42:24 volumioraspberrry sudo[1843]: root : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1843]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry sudo[1843]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:42:24 volumioraspberrry sudo[1843]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 18:42:24 volumioraspberrry sudo[1843]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry sudo[1843]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry volumio[1422]: error: Failed to check for new versions for plugin Ferrum Streaming Control Technology: Error: Invalid argument not valid semver ('' received) Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Bluetooth name changed to Volumio/Raspberrry Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: MPD Permissions set Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:24 volumioraspberrry sudo[1855]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1855]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Starting Shairport Sync Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Preparing to generate the ALSA configuration file Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Asound.conf file written Sep 14 18:42:24 volumioraspberrry sudo[1855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 14 18:42:24 volumioraspberrry sudo[1855]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry sudo[1855]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry sudo[1850]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry sudo[1865]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1865]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry sudo[1865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 18:42:24 volumioraspberrry sudo[1865]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 18:42:24 volumioraspberrry sudo[1866]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1866]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry sudo[1869]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry systemd[1]: shairport-sync.service: Succeeded. Sep 14 18:42:24 volumioraspberrry volumio[1422]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card sndrpihifiberry Sep 14 18:42:24 volumioraspberrry volumio[1422]: Found hardware: "RPi-simple" "" "" "" "" Sep 14 18:42:24 volumioraspberrry volumio[1422]: Hardware is initialized using a generic method Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card sndrpihifiberry Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card vc4hdmi0 Sep 14 18:42:24 volumioraspberrry volumio[1422]: Found hardware: "vc4-hdmi" "" "" "" "" Sep 14 18:42:24 volumioraspberrry volumio[1422]: Hardware is initialized using a generic method Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card vc4hdmi0 Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card vc4hdmi1 Sep 14 18:42:24 volumioraspberrry volumio[1422]: Found hardware: "vc4-hdmi" "" "" "" "" Sep 14 18:42:24 volumioraspberrry volumio[1422]: Hardware is initialized using a generic method Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card vc4hdmi1 Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card HT Sep 14 18:42:24 volumioraspberrry volumio[1422]: Found hardware: "USB-Audio" "USB Mixer" "USB2752:004b" "" "" Sep 14 18:42:24 volumioraspberrry volumio[1422]: Hardware is initialized using a generic method Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card HT Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Output device has changed, restarting MPD Sep 14 18:42:24 volumioraspberrry sudo[1869]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 18:42:24 volumioraspberrry sudo[1866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 14 18:42:24 volumioraspberrry systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 18:42:24 volumioraspberrry sudo[1869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 14 18:42:24 volumioraspberrry sudo[1866]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry sudo[1869]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry sudo[1869]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry sudo[1865]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:42:24 volumioraspberrry sudo[1866]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Output device has changed, restarting Shairport Sync Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:24 volumioraspberrry sudo[1877]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1877]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry sudo[1877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 18:42:24 volumioraspberrry sudo[1877]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry sudo[1877]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:24 volumioraspberrry sudo[1882]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1882]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry sudo[1882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 18:42:24 volumioraspberrry sudo[1882]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: QobuzConnect: setDeactiveState invoked Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Preparing to generate the ALSA configuration file Sep 14 18:42:24 volumioraspberrry systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 14 18:42:24 volumioraspberrry systemd[1]: mpd.service: Succeeded. Sep 14 18:42:24 volumioraspberrry systemd[1]: Stopped Music Player Daemon. Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Reading ALSA contributions from plugins. Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: MPD Permissions set Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Shairport-Sync Started Sep 14 18:42:24 volumioraspberrry systemd[1]: Starting Music Player Daemon... Sep 14 18:42:24 volumioraspberrry sudo[1896]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1896]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry sudo[1896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 14 18:42:24 volumioraspberrry sudo[1896]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Executing endpoint qc_getconfig Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:24 volumioraspberrry qobuz-connect[1878]: 20250914 18:42:24.845 [1878.1878] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:24 volumioraspberrry sudo[1896]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Starting Shairport Sync Sep 14 18:42:24 volumioraspberrry sudo[1891]: root : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1891]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry sudo[1891]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:42:24 volumioraspberrry sudo[1891]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 18:42:24 volumioraspberrry sudo[1891]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry sudo[1891]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Asound.conf file written Sep 14 18:42:24 volumioraspberrry sudo[1898]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1898]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry qobuz-connect[1878]: 20250914 18:42:24.889 [1878.1878] INFO VolumeManager: [0x137f840]: Setting new playback volume: 75 Sep 14 18:42:24 volumioraspberrry qobuz-connect[1878]: 20250914 18:42:24.889 [1878.1878] INFO VolumeManager: [0x137f840]: Setting new mute state: 0 Sep 14 18:42:24 volumioraspberrry qobuz-connect[1878]: 20250914 18:42:24.889 [1878.1878] INFO QobuzConnect: [0x1380210]: Client initialized! Sep 14 18:42:24 volumioraspberrry qobuz-connect[1878]: 20250914 18:42:24.889 [1878.1878] INFO SampleApp: Starting Avahi advertising, name: Volumio/Raspberrry, service name: _qobuz-connect._tcp Sep 14 18:42:24 volumioraspberrry sudo[1898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 14 18:42:24 volumioraspberrry sudo[1898]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry sudo[1906]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1906]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry sudo[1906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 18:42:24 volumioraspberrry sudo[1906]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry sudo[1908]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1908]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry sudo[1908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 14 18:42:24 volumioraspberrry sudo[1908]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry qobuz-connect[1878]: 20250914 18:42:24.901 [1878.1878] INFO LocalConfigManager: [0x137f120]: Starting Local Configuration server Sep 14 18:42:24 volumioraspberrry qobuz-connect[1878]: 20250914 18:42:24.901 [1878.1878] INFO SampleApp: Starting Local configuration server Sep 14 18:42:24 volumioraspberrry qobuz-connect[1878]: 20250914 18:42:24.902 [1878.1878] INFO SampleApp: Connected to UNIX socket client 0x1374f60 Sep 14 18:42:24 volumioraspberrry sudo[1908]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 18:42:24 volumioraspberrry volumio[1422]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:42:24 volumioraspberrry qobuz-connect[1878]: 20250914 18:42:24.904 [1878.1878] INFO SampleApp: Stopping Local configuration server Sep 14 18:42:24 volumioraspberrry systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 14 18:42:24 volumioraspberrry systemd[1]: shairport-sync.service: Succeeded. Sep 14 18:42:24 volumioraspberrry systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 18:42:24 volumioraspberrry systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 18:42:24 volumioraspberrry sudo[1906]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry qobuz-connect[1878]: 20250914 18:42:24.914 [1878.1878] INFO SampleApp: shat down connection on UNIX socket Sep 14 18:42:24 volumioraspberrry systemd[1]: qobuz-connect.service: Succeeded. Sep 14 18:42:24 volumioraspberrry systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card sndrpihifiberry Sep 14 18:42:24 volumioraspberrry volumio[1422]: Found hardware: "RPi-simple" "" "" "" "" Sep 14 18:42:24 volumioraspberrry volumio[1422]: Hardware is initialized using a generic method Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card sndrpihifiberry Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card vc4hdmi0 Sep 14 18:42:24 volumioraspberrry volumio[1422]: Found hardware: "vc4-hdmi" "" "" "" "" Sep 14 18:42:24 volumioraspberrry volumio[1422]: Hardware is initialized using a generic method Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card vc4hdmi0 Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card vc4hdmi1 Sep 14 18:42:24 volumioraspberrry volumio[1422]: Found hardware: "vc4-hdmi" "" "" "" "" Sep 14 18:42:24 volumioraspberrry volumio[1422]: Hardware is initialized using a generic method Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card vc4hdmi1 Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card HT Sep 14 18:42:24 volumioraspberrry volumio[1422]: Found hardware: "USB-Audio" "USB Mixer" "USB2752:004b" "" "" Sep 14 18:42:24 volumioraspberrry volumio[1422]: Hardware is initialized using a generic method Sep 14 18:42:24 volumioraspberrry volumio[1422]: No state is present for card HT Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Output device has changed, restarting MPD Sep 14 18:42:24 volumioraspberrry systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:42:24 volumioraspberrry sudo[1898]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Output device has changed, restarting Shairport Sync Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:24 volumioraspberrry sudo[1920]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1920]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry sudo[1920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 18:42:24 volumioraspberrry sudo[1920]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry sudo[1920]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: QobuzConnect: setDeactiveState invoked Sep 14 18:42:24 volumioraspberrry sudo[1923]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:24 volumioraspberrry sudo[1923]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:24 volumioraspberrry sudo[1923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 18:42:24 volumioraspberrry sudo[1923]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:24 volumioraspberrry systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 14 18:42:24 volumioraspberrry systemd[1]: mpd.service: Succeeded. Sep 14 18:42:24 volumioraspberrry systemd[1]: Stopped Music Player Daemon. Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: MPD Permissions set Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: QobuzConnect: setDeactiveState invoked Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:24 volumioraspberrry volumio[1422]: info: Shairport-Sync Started Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: Executing endpoint qc_getconfig Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:42:25 volumioraspberrry qobuz-connect[1917]: 20250914 18:42:25.001 [1917.1917] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:42:25 volumioraspberrry qobuz-connect[1917]: 20250914 18:42:25.003 [1917.1917] INFO VolumeManager: [0x22a3840]: Setting new playback volume: 75 Sep 14 18:42:25 volumioraspberrry qobuz-connect[1917]: 20250914 18:42:25.003 [1917.1917] INFO VolumeManager: [0x22a3840]: Setting new mute state: 0 Sep 14 18:42:25 volumioraspberrry qobuz-connect[1917]: 20250914 18:42:25.003 [1917.1917] INFO QobuzConnect: [0x22a4210]: Client initialized! Sep 14 18:42:25 volumioraspberrry qobuz-connect[1917]: 20250914 18:42:25.003 [1917.1917] INFO SampleApp: Starting Avahi advertising, name: Volumio/Raspberrry, service name: _qobuz-connect._tcp Sep 14 18:42:25 volumioraspberrry qobuz-connect[1917]: 20250914 18:42:25.006 [1917.1917] INFO LocalConfigManager: [0x22a3120]: Starting Local Configuration server Sep 14 18:42:25 volumioraspberrry qobuz-connect[1917]: 20250914 18:42:25.006 [1917.1917] INFO SampleApp: Starting Local configuration server Sep 14 18:42:25 volumioraspberrry qobuz-connect[1917]: 20250914 18:42:25.006 [1917.1917] INFO SampleApp: Connected to UNIX socket client 0x2298f60 Sep 14 18:42:25 volumioraspberrry sudo[1936]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: Starting Shairport Sync Sep 14 18:42:25 volumioraspberrry sudo[1936]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:25 volumioraspberrry systemd[1]: Starting Music Player Daemon... Sep 14 18:42:25 volumioraspberrry sudo[1936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 14 18:42:25 volumioraspberrry sudo[1936]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:25 volumioraspberrry sudo[1936]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:25 volumioraspberrry sudo[1944]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:25 volumioraspberrry sudo[1944]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:25 volumioraspberrry sudo[1944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 18:42:25 volumioraspberrry sudo[1944]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:25 volumioraspberrry systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 18:42:25 volumioraspberrry systemd[1]: shairport-sync.service: Succeeded. Sep 14 18:42:25 volumioraspberrry systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 18:42:25 volumioraspberrry sudo[1934]: root : unable to resolve host volumioraspberrry Sep 14 18:42:25 volumioraspberrry sudo[1934]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:25 volumioraspberrry sudo[1934]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:42:25 volumioraspberrry sudo[1945]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:25 volumioraspberrry sudo[1945]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:25 volumioraspberrry sudo[1945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 14 18:42:25 volumioraspberrry sudo[1945]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:25 volumioraspberrry sudo[1934]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 18:42:25 volumioraspberrry sudo[1934]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:25 volumioraspberrry sudo[1934]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:25 volumioraspberrry systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 18:42:25 volumioraspberrry sudo[1944]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: Shairport-Sync Started Sep 14 18:42:25 volumioraspberrry systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 14 18:42:25 volumioraspberrry qobuz-connect[1917]: 20250914 18:42:25.107 [1917.1917] INFO SampleApp: Stopping Local configuration server Sep 14 18:42:25 volumioraspberrry qobuz-connect[1917]: 20250914 18:42:25.117 [1917.1917] INFO SampleApp: shat down connection on UNIX socket Sep 14 18:42:25 volumioraspberrry systemd[1]: qobuz-connect.service: Succeeded. Sep 14 18:42:25 volumioraspberrry systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: QobuzConnect: setDeactiveState invoked Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:25 volumioraspberrry systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:42:25 volumioraspberrry sudo[1945]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: Executing endpoint qc_getconfig Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 14 18:42:25 volumioraspberrry qobuz-connect[1953]: 20250914 18:42:25.135 [1953.1953] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:42:25 volumioraspberrry qobuz-connect[1953]: 20250914 18:42:25.136 [1953.1953] INFO VolumeManager: [0x1356840]: Setting new playback volume: 75 Sep 14 18:42:25 volumioraspberrry qobuz-connect[1953]: 20250914 18:42:25.136 [1953.1953] INFO VolumeManager: [0x1356840]: Setting new mute state: 0 Sep 14 18:42:25 volumioraspberrry qobuz-connect[1953]: 20250914 18:42:25.136 [1953.1953] INFO QobuzConnect: [0x1357210]: Client initialized! Sep 14 18:42:25 volumioraspberrry qobuz-connect[1953]: 20250914 18:42:25.136 [1953.1953] INFO SampleApp: Starting Avahi advertising, name: Volumio/Raspberrry, service name: _qobuz-connect._tcp Sep 14 18:42:25 volumioraspberrry qobuz-connect[1953]: 20250914 18:42:25.138 [1953.1953] INFO LocalConfigManager: [0x1356120]: Starting Local Configuration server Sep 14 18:42:25 volumioraspberrry qobuz-connect[1953]: 20250914 18:42:25.138 [1953.1953] INFO SampleApp: Starting Local configuration server Sep 14 18:42:25 volumioraspberrry qobuz-connect[1953]: 20250914 18:42:25.138 [1953.1953] INFO SampleApp: Connected to UNIX socket client 0x134bf60 Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: Setting Geolocation for MyVolumio to eu4 Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: Successfully Added MyVolumio device Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: Access Token successfully retrieved Sep 14 18:42:25 volumioraspberrry qobuz-connect[1953]: 20250914 18:42:25.285 [1953.1953] INFO SampleApp: Playback volume changed: 75 Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:25 volumioraspberrry mpd[1950]: Sep 14 18:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 18:42:25 volumioraspberrry systemd[1]: Started Music Player Daemon. Sep 14 18:42:25 volumioraspberrry sudo[1882]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:25 volumioraspberrry sudo[1832]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:25 volumioraspberrry sudo[1923]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:25 volumioraspberrry volumio[1422]: error: MPD error: The expression evaluated to a falsy value: Sep 14 18:42:25 volumioraspberrry volumio[1422]: assert.ok(self.idling) Sep 14 18:42:25 volumioraspberrry volumio[1422]: error: The expression evaluated to a falsy value: Sep 14 18:42:25 volumioraspberrry volumio[1422]: assert.ok(self.idling) Sep 14 18:42:25 volumioraspberrry volumio[1422]: error: MPD error: The expression evaluated to a falsy value: Sep 14 18:42:25 volumioraspberrry volumio[1422]: assert.ok(self.idling) Sep 14 18:42:25 volumioraspberrry volumio[1422]: error: The expression evaluated to a falsy value: Sep 14 18:42:25 volumioraspberrry volumio[1422]: assert.ok(self.idling) Sep 14 18:42:25 volumioraspberrry volumio[1422]: error: updateQueue error: null Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: Updating MyVolumio device info Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: Successfully retrieved User Session From TIDAL Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: Successfully Added MyVolumio device Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: Successfully retrieved User Subscription From TIDAL Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: Adding TIDAL to Browse Sources Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: [1757868145778] CoreMusicLibrary::Adding element TIDAL Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 18:42:25 volumioraspberrry volumio[1422]: Cannot find translation for source TIDAL Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: Stopping AccessToken refresher cron Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: AccessToken refresher cron started Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: Adding TIDAL REST API Endpoints Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Sep 14 18:42:25 volumioraspberrry volumio[1422]: info: Successfully Updated MyVolumio device Sep 14 18:42:26 volumioraspberrry volumio[1422]: info: Updating MyVolumio device info Sep 14 18:42:26 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:26 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:26 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:26 volumioraspberrry volumio[1422]: info: Successfully Updated MyVolumio device Sep 14 18:42:27 volumioraspberrry sudo[1973]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:27 volumioraspberrry sudo[1973]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:27 volumioraspberrry sudo[1973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Sep 14 18:42:27 volumioraspberrry sudo[1973]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:27 volumioraspberrry systemd[1]: Started Volumio Bluetooth Module. Sep 14 18:42:27 volumioraspberrry sudo[1973]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: Volumio BT Module successfully started Sep 14 18:42:27 volumioraspberrry sudo[1976]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:27 volumioraspberrry sudo[1976]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:27 volumioraspberrry volumiobt[1975]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:42:27 volumioraspberrry sudo[1976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Sep 14 18:42:27 volumioraspberrry sudo[1976]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:27 volumioraspberrry volumiobt[1975]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Sep 14 18:42:27 volumioraspberrry sudo[1976]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:27 volumioraspberrry volumio[1422]: error: Unable to stop TidalConnect service: Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: TidalConnect service stoped! Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 14 18:42:27 volumioraspberrry sudo[1988]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:27 volumioraspberrry sudo[1988]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:27 volumioraspberrry sudo[1988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 14 18:42:27 volumioraspberrry sudo[1988]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:27 volumioraspberrry systemd[1]: Started Volumio Tidal Connect Service. Sep 14 18:42:27 volumioraspberrry sudo[1988]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: Executing endpoint tc_getconfig Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 14 18:42:27 volumioraspberrry vtcs[1990]: STARTING TidalConnect services, version: 1.5.1-beta1 Sep 14 18:42:27 volumioraspberrry vtcs[1990]: STARTED TidalConnect services. Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: Executing endpoint tc_connect Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: Connecting to TidalConnect Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreCommandRouter::servicePushState Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreStateMachine::pushState Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioPushState Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: MRS: Pushing multiroomSync output Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:27 volumioraspberrry volumio[1422]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Sep 14 18:42:27 volumioraspberrry volumio[1422]: verbose: CURRENT POSITION 0 Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreStateMachine::syncState stateService stop Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreStateMachine::pushState Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioPushState Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: MRS: Pushing multiroomSync output Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: No code Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreStateMachine::pushState Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioPushState Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: MRS: Pushing multiroomSync output Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreCommandRouter::servicePushState Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreStateMachine::pushState Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioPushState Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: MRS: Pushing multiroomSync output Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:27 volumioraspberrry volumio[1422]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Sep 14 18:42:27 volumioraspberrry volumio[1422]: verbose: CURRENT POSITION 0 Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreStateMachine::syncState stateService stop Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreStateMachine::pushState Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioPushState Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: MRS: Pushing multiroomSync output Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: No code Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreStateMachine::pushState Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioPushState Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: MRS: Pushing multiroomSync output Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: [ASDebug] CurState: stop PrevState: stop Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: [ASDebug] CurState: stop PrevState: stop Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: [ASDebug] CurState: stop PrevState: stop Sep 14 18:42:27 volumioraspberrry volumio[1422]: info: [ASDebug] CurState: stop PrevState: stop Sep 14 18:42:28 volumioraspberrry sudo[2014]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:28 volumioraspberrry sudo[2014]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:28 volumioraspberrry sudo[2014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Sep 14 18:42:28 volumioraspberrry sudo[2014]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:28 volumioraspberrry systemd[1]: Started MyVolumio SSH Tunnel. Sep 14 18:42:28 volumioraspberrry sudo[2014]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:28 volumioraspberrry volumio[1422]: info: Remote SSH Started Sep 14 18:42:28 volumioraspberrry autossh[2017]: port set to 0, monitoring disabled Sep 14 18:42:28 volumioraspberrry autossh[2017]: starting ssh (count 1) Sep 14 18:42:28 volumioraspberrry autossh[2017]: ssh child pid is 2020 Sep 14 18:42:28 volumioraspberrry volumio[1422]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 14 18:42:28 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:28 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:28 volumioraspberrry sudo[2021]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:28 volumioraspberrry sudo[2021]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:42:28 volumioraspberrry sudo[2021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Sep 14 18:42:28 volumioraspberrry sudo[2021]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: pulseaudio: no process found Sep 14 18:42:28 volumioraspberrry sudo[2021]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:28 volumioraspberrry dbus-daemon[903]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.30' (uid=1000 pid=2025 comm="/usr/bin/pulseaudio --high-priority=true ") Sep 14 18:42:28 volumioraspberrry systemd[1]: Starting RealtimeKit Scheduling Policy Service... Sep 14 18:42:28 volumioraspberrry volumio[1422]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 14 18:42:28 volumioraspberrry dbus-daemon[903]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Sep 14 18:42:28 volumioraspberrry systemd[1]: Started RealtimeKit Scheduling Policy Service. Sep 14 18:42:28 volumioraspberrry rtkit-daemon[2026]: Successfully called chroot. Sep 14 18:42:28 volumioraspberrry rtkit-daemon[2026]: Successfully dropped privileges. Sep 14 18:42:28 volumioraspberrry rtkit-daemon[2026]: Successfully limited resources. Sep 14 18:42:28 volumioraspberrry rtkit-daemon[2026]: Running. Sep 14 18:42:28 volumioraspberrry rtkit-daemon[2026]: Watchdog thread running. Sep 14 18:42:28 volumioraspberrry rtkit-daemon[2026]: Canary thread running. Sep 14 18:42:28 volumioraspberrry dbus-daemon[903]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.31' (uid=0 pid=2026 comm="/usr/lib/rtkit/rtkit-daemon ") Sep 14 18:42:28 volumioraspberrry systemd[1]: Starting Authorization Manager... Sep 14 18:42:28 volumioraspberrry volumio[1422]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 14 18:42:28 volumioraspberrry polkitd[2030]: started daemon version 0.105 using authority implementation `local' version `0.105' Sep 14 18:42:28 volumioraspberrry dbus-daemon[903]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Sep 14 18:42:28 volumioraspberrry systemd[1]: Started Authorization Manager. Sep 14 18:42:28 volumioraspberrry volumiossh-tunnel[2016]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Sep 14 18:42:28 volumioraspberrry pulseaudio[2025]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 14 18:42:28 volumioraspberrry pulseaudio[2025]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: Applying permissions Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: Setting BT discoverable and pairable Sep 14 18:42:28 volumioraspberrry bluetoothd[1011]: Endpoint registered: sender=:1.51 path=/MediaEndpoint/A2DPSource Sep 14 18:42:28 volumioraspberrry bluetoothd[1011]: Endpoint registered: sender=:1.51 path=/MediaEndpoint/A2DPSink Sep 14 18:42:28 volumioraspberrry pulseaudio[2025]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Sep 14 18:42:28 volumioraspberrry kernel: Bluetooth: RFCOMM TTY layer initialized Sep 14 18:42:28 volumioraspberrry kernel: Bluetooth: RFCOMM socket layer initialized Sep 14 18:42:28 volumioraspberrry kernel: Bluetooth: RFCOMM ver 1.11 Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: [CHG] Controller 88:A2:9E:2F:BD:52 Class: 0x0008041c Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: [55B blob data] Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: [bluetooth]# -e power on Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: Invalid command in menu main: -e Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: Use "help" for a list of available commands in a menu. Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: Use "menu " if you want to enter any submenu. Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: Use "back" if you want to return to menu main. Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: [bluetooth]# agent on Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: Agent is already registered Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: [bluetooth]# discoverable on Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: [bluetooth]# pairable on Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: [bluetooth]# agent NoInputNoOutput Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: Agent is already registered Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: [bluetooth]# default-agent Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: [bluetooth]# quit Sep 14 18:42:28 volumioraspberrry volumiobt[1975]: [59B blob data] Sep 14 18:42:29 volumioraspberrry volumio[1422]: info: MRS: Getting audio outputs on start Sep 14 18:42:29 volumioraspberrry volumio[1422]: info: MRS: Requesting all other devices output Sep 14 18:42:29 volumioraspberrry volumio[1422]: info: Enabling plugin fusiondsp Sep 14 18:42:29 volumioraspberrry volumio[1422]: info: Loading plugin "fusiondsp"... Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: Preparing to generate the ALSA configuration file Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: Reading ALSA contributions from plugins. Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: Asound.conf file unchanged, so no further update is needed Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: Output device has changed, restarting MPD Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: Output device has changed, restarting Shairport Sync Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:30 volumioraspberrry sudo[2041]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:30 volumioraspberrry sudo[2041]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:30 volumioraspberrry sudo[2043]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:30 volumioraspberrry sudo[2043]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:30 volumioraspberrry sudo[2041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 18:42:30 volumioraspberrry sudo[2041]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:30 volumioraspberrry volumio[1422]: xcb_connection_has_error() returned true Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 18:42:30 volumioraspberrry volumio[1422]: xcb_connection_has_error() returned true Sep 14 18:42:30 volumioraspberrry sudo[2043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 14 18:42:30 volumioraspberrry sudo[2043]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry sudo[2041]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:30 volumioraspberrry systemd[1]: Stopping Music Player Daemon... Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: QobuzConnect: setDeactiveState invoked Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:30 volumioraspberrry vtcs[1990]: [2025-09-14 18:42:30.185] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Sep 14 18:42:30 volumioraspberrry vtcs[1990]: [2025-09-14 18:42:30.185] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Sep 14 18:42:30 volumioraspberrry systemd[1]: mpd.service: Succeeded. Sep 14 18:42:30 volumioraspberrry systemd[1]: Stopped Music Player Daemon. Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: PLUGIN START: fusiondsp Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: Loading i18n strings for locale de Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: FusionDsp - mixtype--------------------- None Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: Preparing to generate the ALSA configuration file Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: Done. Sep 14 18:42:30 volumioraspberrry sudo[2053]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:30 volumioraspberrry sudo[2053]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:30 volumioraspberrry sudo[2053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 14 18:42:30 volumioraspberrry sudo[2053]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:30 volumioraspberrry systemd[1]: Starting Music Player Daemon... Sep 14 18:42:30 volumioraspberrry systemd[1]: Stopping Volumio Tidal Connect Service... Sep 14 18:42:30 volumioraspberrry systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Sep 14 18:42:30 volumioraspberrry systemd[1]: vtcs.service: Succeeded. Sep 14 18:42:30 volumioraspberrry systemd[1]: Stopped Volumio Tidal Connect Service. Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 14 18:42:30 volumioraspberrry sudo[2055]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: Reading ALSA contributions from plugins. Sep 14 18:42:30 volumioraspberrry volumio[1422]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 14 18:42:30 volumioraspberrry volumio[1422]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 14 18:42:30 volumioraspberrry volumio[1422]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 14 18:42:30 volumioraspberrry sudo[2055]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:30 volumioraspberrry sudo[2055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 14 18:42:30 volumioraspberrry sudo[2055]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:30 volumioraspberrry sudo[2053]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:30 volumioraspberrry volumio[1422]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: MPD Permissions set Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry sudo[2055]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: Starting Shairport Sync Sep 14 18:42:30 volumioraspberrry sudo[2061]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:30 volumioraspberrry sudo[2061]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:30 volumioraspberrry sudo[2061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 14 18:42:30 volumioraspberrry sudo[2061]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:30 volumioraspberrry sudo[2052]: root : unable to resolve host volumioraspberrry Sep 14 18:42:30 volumioraspberrry sudo[2052]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:30 volumioraspberrry sudo[2052]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:42:30 volumioraspberrry sudo[2052]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 18:42:30 volumioraspberrry sudo[2052]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:30 volumioraspberrry sudo[2052]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: Asound.conf file unchanged, so no further update is needed Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: Output device has changed, restarting MPD Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: Output device has changed, restarting Shairport Sync Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:30 volumioraspberrry sudo[2072]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:30 volumioraspberrry sudo[2072]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:30 volumioraspberrry volumio[1422]: xcb_connection_has_error() returned true Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 18:42:30 volumioraspberrry volumio[1422]: xcb_connection_has_error() returned true Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: QobuzConnect: setDeactiveState invoked Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:30 volumioraspberrry sudo[2072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 18:42:30 volumioraspberrry sudo[2072]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:30 volumioraspberrry sudo[2075]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:30 volumioraspberrry sudo[2075]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:30 volumioraspberrry sudo[2075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 18:42:30 volumioraspberrry sudo[2075]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:30 volumioraspberrry sudo[2075]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:30 volumioraspberrry sudo[2077]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:30 volumioraspberrry sudo[2077]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:30 volumioraspberrry sudo[2077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 18:42:30 volumioraspberrry sudo[2077]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:30 volumioraspberrry sudo[2061]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:30 volumioraspberrry systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 14 18:42:30 volumioraspberrry systemd[1]: mpd.service: Succeeded. Sep 14 18:42:30 volumioraspberrry systemd[1]: Stopped Music Player Daemon. Sep 14 18:42:30 volumioraspberrry systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 18:42:30 volumioraspberrry systemd[1]: Starting Music Player Daemon... Sep 14 18:42:30 volumioraspberrry systemd[1]: shairport-sync.service: Succeeded. Sep 14 18:42:30 volumioraspberrry systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 18:42:30 volumioraspberrry systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 18:42:30 volumioraspberrry sudo[2072]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:30 volumioraspberrry sudo[2087]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:30 volumioraspberrry sudo[2087]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:30 volumioraspberrry sudo[2087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 14 18:42:30 volumioraspberrry sudo[2087]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:30 volumioraspberrry volumio[1422]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 14 18:42:30 volumioraspberrry sudo[2089]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:30 volumioraspberrry sudo[2089]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:30 volumioraspberrry sudo[2089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 14 18:42:30 volumioraspberrry volumio[1422]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 14 18:42:30 volumioraspberrry volumio[1422]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 14 18:42:30 volumioraspberrry sudo[2089]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:30 volumioraspberrry sudo[2090]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:30 volumioraspberrry volumio[1422]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 14 18:42:30 volumioraspberrry volumio[1422]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 14 18:42:30 volumioraspberrry sudo[2090]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:30 volumioraspberrry sudo[2090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 14 18:42:30 volumioraspberrry sudo[2090]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: MPD Permissions set Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: Shairport-Sync Started Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry sudo[2087]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:30 volumioraspberrry sudo[2098]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:30 volumioraspberrry systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 14 18:42:30 volumioraspberrry sudo[2098]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:30 volumioraspberrry qobuz-connect[1953]: 20250914 18:42:30.372 [1953.1953] INFO SampleApp: Stopping Local configuration server Sep 14 18:42:30 volumioraspberrry sudo[2090]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:30 volumioraspberrry sudo[2098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 14 18:42:30 volumioraspberrry sudo[2098]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:30 volumioraspberrry sudo[2091]: root : unable to resolve host volumioraspberrry Sep 14 18:42:30 volumioraspberrry sudo[2091]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:30 volumioraspberrry sudo[2091]: sudo: unable to resolve host volumioraspberrry: System error Sep 14 18:42:30 volumioraspberrry sudo[2091]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 18:42:30 volumioraspberrry sudo[2091]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:30 volumioraspberrry sudo[2091]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 14 18:42:30 volumioraspberrry qobuz-connect[1953]: 20250914 18:42:30.383 [1953.1953] INFO SampleApp: shat down connection on UNIX socket Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: QobuzConnect: setDeactiveState invoked Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: Starting Shairport Sync Sep 14 18:42:30 volumioraspberrry volumio[1422]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 14 18:42:30 volumioraspberrry systemd[1]: qobuz-connect.service: Succeeded. Sep 14 18:42:30 volumioraspberrry systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:42:30 volumioraspberrry systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:42:30 volumioraspberrry sudo[2089]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:30 volumioraspberrry sudo[2098]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:30 volumioraspberrry sudo[2113]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:30 volumioraspberrry sudo[2113]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:30 volumioraspberrry sudo[2113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 18:42:30 volumioraspberrry sudo[2113]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: Executing endpoint qc_getconfig Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:42:30 volumioraspberrry qobuz-connect[2114]: 20250914 18:42:30.416 [2114.2114] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 14 18:42:30 volumioraspberrry qobuz-connect[2114]: 20250914 18:42:30.418 [2114.2114] INFO VolumeManager: [0x1b2c840]: Setting new playback volume: 75 Sep 14 18:42:30 volumioraspberrry qobuz-connect[2114]: 20250914 18:42:30.418 [2114.2114] INFO VolumeManager: [0x1b2c840]: Setting new mute state: 0 Sep 14 18:42:30 volumioraspberrry qobuz-connect[2114]: 20250914 18:42:30.418 [2114.2114] INFO QobuzConnect: [0x1b2d210]: Client initialized! Sep 14 18:42:30 volumioraspberrry qobuz-connect[2114]: 20250914 18:42:30.418 [2114.2114] INFO SampleApp: Starting Avahi advertising, name: Volumio/Raspberrry, service name: _qobuz-connect._tcp Sep 14 18:42:30 volumioraspberrry systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 18:42:30 volumioraspberrry systemd[1]: shairport-sync.service: Succeeded. Sep 14 18:42:30 volumioraspberrry systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 18:42:30 volumioraspberrry volumio[1422]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 14 18:42:30 volumioraspberrry qobuz-connect[2114]: 20250914 18:42:30.424 [2114.2114] INFO LocalConfigManager: [0x1b2c120]: Starting Local Configuration server Sep 14 18:42:30 volumioraspberrry qobuz-connect[2114]: 20250914 18:42:30.424 [2114.2114] INFO SampleApp: Starting Local configuration server Sep 14 18:42:30 volumioraspberrry qobuz-connect[2114]: 20250914 18:42:30.424 [2114.2114] INFO SampleApp: Connected to UNIX socket client 0x1b21f60 Sep 14 18:42:30 volumioraspberrry volumio[1422]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 14 18:42:30 volumioraspberrry systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 18:42:30 volumioraspberrry sudo[2113]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:30 volumioraspberrry sudo[2115]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:30 volumioraspberrry sudo[2115]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:30 volumioraspberrry sudo[2115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 14 18:42:30 volumioraspberrry sudo[2115]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: Shairport-Sync Started Sep 14 18:42:30 volumioraspberrry systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 14 18:42:30 volumioraspberrry qobuz-connect[2114]: 20250914 18:42:30.441 [2114.2114] INFO SampleApp: Stopping Local configuration server Sep 14 18:42:30 volumioraspberrry volumio[1422]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 14 18:42:30 volumioraspberrry qobuz-connect[2114]: 20250914 18:42:30.451 [2114.2114] INFO SampleApp: shat down connection on UNIX socket Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: QobuzConnect: setDeactiveState invoked Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:30 volumioraspberrry systemd[1]: qobuz-connect.service: Succeeded. Sep 14 18:42:30 volumioraspberrry systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:42:30 volumioraspberrry volumio[1422]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 14 18:42:30 volumioraspberrry systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:42:30 volumioraspberrry sudo[2115]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: Executing endpoint qc_getconfig Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:42:30 volumioraspberrry qobuz-connect[2123]: 20250914 18:42:30.488 [2123.2123] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:42:30 volumioraspberrry qobuz-connect[2123]: 20250914 18:42:30.490 [2123.2123] INFO VolumeManager: [0x166c840]: Setting new playback volume: 75 Sep 14 18:42:30 volumioraspberrry qobuz-connect[2123]: 20250914 18:42:30.490 [2123.2123] INFO VolumeManager: [0x166c840]: Setting new mute state: 0 Sep 14 18:42:30 volumioraspberrry qobuz-connect[2123]: 20250914 18:42:30.490 [2123.2123] INFO QobuzConnect: [0x166d210]: Client initialized! Sep 14 18:42:30 volumioraspberrry qobuz-connect[2123]: 20250914 18:42:30.490 [2123.2123] INFO SampleApp: Starting Avahi advertising, name: Volumio/Raspberrry, service name: _qobuz-connect._tcp Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 14 18:42:30 volumioraspberrry qobuz-connect[2123]: 20250914 18:42:30.497 [2123.2123] INFO LocalConfigManager: [0x166c120]: Starting Local Configuration server Sep 14 18:42:30 volumioraspberrry qobuz-connect[2123]: 20250914 18:42:30.497 [2123.2123] INFO SampleApp: Starting Local configuration server Sep 14 18:42:30 volumioraspberrry qobuz-connect[2123]: 20250914 18:42:30.497 [2123.2123] INFO SampleApp: Connected to UNIX socket client 0x1661f60 Sep 14 18:42:30 volumioraspberrry mpd[2111]: Sep 14 18:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 18:42:30 volumioraspberrry systemd[1]: Started Music Player Daemon. Sep 14 18:42:30 volumioraspberrry sudo[2077]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:30 volumioraspberrry sudo[2043]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:30 volumioraspberrry volumio[1422]: error: updateQueue error: null Sep 14 18:42:30 volumioraspberrry volumio[1422]: error: updateQueue error: null Sep 14 18:42:30 volumioraspberrry qobuz-connect[2123]: 20250914 18:42:30.639 [2123.2123] INFO SampleApp: Playback volume changed: 75 Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumioGetState Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CorePlayQueue::getTrack 0 Sep 14 18:42:30 volumioraspberrry volumio[1422]: error: Unable to start TidalConnect service: Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: TidalConnect service started! Sep 14 18:42:30 volumioraspberrry volumio[1422]: [Metrics] CommandRouter: 25s 663.22ms Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::volumiosetStartupVolume Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::Close All Modals sent Sep 14 18:42:30 volumioraspberrry volumio[1422]: info: CoreCommandRouter::Close All Modals sent Sep 14 18:42:31 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 14 18:42:31 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 14 18:42:31 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 14 18:42:32 volumioraspberrry volumio[1422]: info: camilladsp service started and running in background, instance 1 Sep 14 18:42:32 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 18:42:32 volumioraspberrry volumio[1422]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 14 18:42:32 volumioraspberrry volumio[1422]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 14 18:42:32 volumioraspberrry volumio[1422]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 14 18:42:32 volumioraspberrry volumio[1422]: info: FusionDsp loaded Sep 14 18:42:32 volumioraspberrry volumio[1422]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 18:42:32 volumioraspberrry volumio[1422]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 14 18:42:32 volumioraspberrry volumio[1422]: info: Adding Signal Path Element [object Object] Sep 14 18:42:32 volumioraspberrry volumio[1422]: info: Adding fusiondspeq DSP Signal Path Element Sep 14 18:42:32 volumioraspberrry volumio[1422]: info: FusionDsp - ---- installed callbackRead Sep 14 18:42:32 volumioraspberrry volumio[1422]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 18:42:32 volumioraspberrry volumio[1422]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 14 18:42:32 volumioraspberrry volumio[1422]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 14 18:42:32 volumioraspberrry volumio[1422]: at onErrorNT (internal/child_process.js:465:16) Sep 14 18:42:32 volumioraspberrry volumio[1422]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 14 18:42:32 volumioraspberrry volumio[1422]: errno: -2, Sep 14 18:42:32 volumioraspberrry volumio[1422]: code: 'ENOENT', Sep 14 18:42:32 volumioraspberrry volumio[1422]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 14 18:42:32 volumioraspberrry volumio[1422]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 14 18:42:32 volumioraspberrry volumio[1422]: spawnargs: [ Sep 14 18:42:32 volumioraspberrry volumio[1422]: '-p', Sep 14 18:42:32 volumioraspberrry volumio[1422]: 9876, Sep 14 18:42:32 volumioraspberrry volumio[1422]: '-o', Sep 14 18:42:32 volumioraspberrry volumio[1422]: '/tmp/camilladsp.log', Sep 14 18:42:32 volumioraspberrry volumio[1422]: '-l', Sep 14 18:42:32 volumioraspberrry volumio[1422]: 'warn', Sep 14 18:42:32 volumioraspberrry volumio[1422]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 14 18:42:32 volumioraspberrry volumio[1422]: ] Sep 14 18:42:32 volumioraspberrry volumio[1422]: } Sep 14 18:42:32 volumioraspberrry volumio[1422]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 18:42:32 volumioraspberrry sudo[2140]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:32 volumioraspberrry sudo[2140]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:32 volumioraspberrry sudo[2140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 14 18:42:32 volumioraspberrry sudo[2140]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:42:32 volumioraspberrry sudo[2140]: pam_unix(sudo:session): session closed for user root Sep 14 18:42:32 volumioraspberrry sudo[2151]: volumio : unable to resolve host volumioraspberrry Sep 14 18:42:32 volumioraspberrry sudo[2151]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 14 18:42:32 volumioraspberrry sudo[2151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 18:41 Sep 14 18:42:32 volumioraspberrry sudo[2151]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"