-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Mon 2026-03-09 05:41:18 GMT. -- Mar 09 05:40:00 sauls-volumio bluetoothd[702]: Failed to set privacy: Rejected (0x0b) Mar 09 05:40:00 sauls-volumio nmbd[699]: [2026/03/09 05:40:00.489945, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Mar 09 05:40:00 sauls-volumio nmbd[699]: started asyncdns process 788 Mar 09 05:40:00 sauls-volumio nmbd[699]: [2026/03/09 05:40:00.493006, 0] ../lib/util/become_daemon.c:149(daemon_status) Mar 09 05:40:00 sauls-volumio nmbd[699]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Mar 09 05:40:00 sauls-volumio nmbd[699]: [2026/03/09 05:40:00.493675, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Mar 09 05:40:00 sauls-volumio nmbd[699]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Mar 09 05:40:00 sauls-volumio wireless.js[576]: Cleaning previous... Mar 09 05:40:00 sauls-volumio ntpd[749]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 09 05:40:00 sauls-volumio sudo[796]: root : unable to resolve host sauls-volumio Mar 09 05:40:00 sauls-volumio sudo[796]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:00 sauls-volumio sudo[796]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 09 05:40:00 sauls-volumio sudo[796]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:00 sauls-volumio sudo[796]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:00 sauls-volumio sudo[798]: root : unable to resolve host sauls-volumio Mar 09 05:40:00 sauls-volumio sudo[798]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:00 sauls-volumio sudo[798]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 09 05:40:00 sauls-volumio sudo[798]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:01 sauls-volumio sudo[798]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:01 sauls-volumio wireless.js[576]: Stopped aP Mar 09 05:40:01 sauls-volumio sudo[805]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:01 sauls-volumio sudo[805]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:01 sauls-volumio sudo[805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 09 05:40:01 sauls-volumio sudo[805]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:01 sauls-volumio sudo[805]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:01 sauls-volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 09 05:40:01 sauls-volumio sudo[807]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:01 sauls-volumio sudo[807]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:01 sauls-volumio sudo[807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 09 05:40:01 sauls-volumio sudo[807]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:01 sauls-volumio sudo[807]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:01 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:01 sauls-volumio sudo[814]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:01 sauls-volumio sudo[814]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:01 sauls-volumio sudo[814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 09 05:40:01 sauls-volumio sudo[814]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:01 sauls-volumio sudo[814]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:01 sauls-volumio sudo[816]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:01 sauls-volumio sudo[816]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:01 sauls-volumio sudo[816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 09 05:40:01 sauls-volumio sudo[816]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:01 sauls-volumio ntpd[749]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 09 05:40:02 sauls-volumio systemd[1]: systemd-rfkill.service: Succeeded. Mar 09 05:40:02 sauls-volumio ntpd[749]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 09 05:40:03 sauls-volumio ntpd[749]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 09 05:40:04 sauls-volumio sudo[816]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:04 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:04 sauls-volumio wireless.js[576]: SETTING APPROPRIATE REG DOMAIN: GB Mar 09 05:40:04 sauls-volumio sudo[821]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:04 sauls-volumio sudo[821]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:04 sauls-volumio sudo[821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 09 05:40:04 sauls-volumio sudo[821]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:04 sauls-volumio sudo[821]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:04 sauls-volumio sudo[823]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:04 sauls-volumio sudo[823]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:04 sauls-volumio sudo[823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set GB Mar 09 05:40:04 sauls-volumio sudo[823]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:04 sauls-volumio sudo[823]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:04 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:04 sauls-volumio wireless.js[576]: SUCCESSFULLY SET NEW REGDOMAIN: GB Mar 09 05:40:04 sauls-volumio wireless.js[576]: Start wireless flow Mar 09 05:40:04 sauls-volumio wireless.js[576]: Stopped hotspot (if there).. Mar 09 05:40:04 sauls-volumio sudo[828]: root : unable to resolve host sauls-volumio Mar 09 05:40:04 sauls-volumio sudo[828]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:04 sauls-volumio sudo[828]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 09 05:40:04 sauls-volumio sudo[828]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:04 sauls-volumio sudo[828]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:04 sauls-volumio sudo[830]: root : unable to resolve host sauls-volumio Mar 09 05:40:04 sauls-volumio sudo[830]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:04 sauls-volumio sudo[830]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 09 05:40:04 sauls-volumio sudo[830]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:04 sauls-volumio bluetoothd[702]: src/adv_monitor.c:btd_adv_monitor_power_down() Unexpected NULL btd_adv_monitor_manager object upon power down Mar 09 05:40:04 sauls-volumio bthelper[648]: [NEW] Media /org/bluez/hci0 Mar 09 05:40:04 sauls-volumio bthelper[648]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Mar 09 05:40:04 sauls-volumio bthelper[648]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Mar 09 05:40:04 sauls-volumio bthelper[648]: [CHG] Controller E4:5F:01:02:C6:4F PowerState: on-disabling Mar 09 05:40:04 sauls-volumio bthelper[648]: hci0 class of device changed: 0x000000 Mar 09 05:40:04 sauls-volumio bthelper[648]: hci0 new_settings: connectable discoverable bondable ssp br/edr le secure-conn Mar 09 05:40:04 sauls-volumio bthelper[648]: Changing power off succeeded Mar 09 05:40:04 sauls-volumio bthelper[648]: [NEW] Media /org/bluez/hci0 Mar 09 05:40:04 sauls-volumio bthelper[648]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Mar 09 05:40:04 sauls-volumio bthelper[648]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Mar 09 05:40:04 sauls-volumio bthelper[648]: [CHG] Controller E4:5F:01:02:C6:4F PowerState: off-enabling Mar 09 05:40:04 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Fetching time from Volumio... Mar 09 05:40:04 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Date not found in response Mar 09 05:40:04 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Retrying in 5 seconds... Mar 09 05:40:04 sauls-volumio bthelper[648]: hci0 class of device changed: 0x00041c Mar 09 05:40:04 sauls-volumio bthelper[648]: [CHG] Controller E4:5F:01:02:C6:4F Class: 0x0000041c (1052) Mar 09 05:40:04 sauls-volumio bthelper[648]: hci0 new_settings: powered connectable discoverable bondable ssp br/edr le secure-conn Mar 09 05:40:04 sauls-volumio bthelper[648]: Changing power on succeeded Mar 09 05:40:04 sauls-volumio volumio-remote-updater[574]: [2026-03-09 05:40:04] [info] asio async_connect error: system:111 (Connection refused) Mar 09 05:40:04 sauls-volumio volumio-remote-updater[574]: [2026-03-09 05:40:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 09 05:40:04 sauls-volumio volumio-remote-updater[574]: [2026-03-09 05:40:04] [error] handle_connect error: Underlying Transport Error Mar 09 05:40:04 sauls-volumio sudo[830]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:04 sauls-volumio wireless.js[576]: DHCP IP Mar 09 05:40:04 sauls-volumio wireless.js[576]: Start ap Mar 09 05:40:04 sauls-volumio wpa_supplicant[838]: Successfully initialized wpa_supplicant Mar 09 05:40:04 sauls-volumio sudo[839]: root : unable to resolve host sauls-volumio Mar 09 05:40:04 sauls-volumio sudo[839]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:04 sauls-volumio sudo[839]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Mar 09 05:40:04 sauls-volumio sudo[839]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:04 sauls-volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 09 05:40:04 sauls-volumio dhcpcd[840]: dev: loaded udev Mar 09 05:40:04 sauls-volumio dhcpcd-run-hooks[858]: wlan0: starting wpa_supplicant Mar 09 05:40:05 sauls-volumio dhcpcd-run-hooks[863]: wlan0: failed to start wpa_supplicant Mar 09 05:40:05 sauls-volumio dhcpcd-run-hooks[864]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Mar 09 05:40:05 sauls-volumio dhcpcd[840]: wlan0: connected to Access Point `' Mar 09 05:40:05 sauls-volumio dhcpcd[840]: no interfaces have a carrier Mar 09 05:40:05 sauls-volumio dhcpcd[840]: forked to background, child pid 874 Mar 09 05:40:05 sauls-volumio dhcpcd[874]: eth0: waiting for carrier Mar 09 05:40:05 sauls-volumio dhcpcd[874]: wlan0: waiting for carrier Mar 09 05:40:05 sauls-volumio sudo[839]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:05 sauls-volumio wireless.js[576]: trying... Mar 09 05:40:05 sauls-volumio sudo[876]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:05 sauls-volumio sudo[876]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:05 sauls-volumio sudo[876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 09 05:40:05 sauls-volumio sudo[876]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:05 sauls-volumio sudo[876]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:05 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:06 sauls-volumio wireless.js[576]: trying... Mar 09 05:40:06 sauls-volumio sudo[879]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:06 sauls-volumio sudo[879]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:06 sauls-volumio sudo[879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 09 05:40:06 sauls-volumio sudo[879]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:06 sauls-volumio sudo[879]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:06 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:07 sauls-volumio wireless.js[576]: trying... Mar 09 05:40:07 sauls-volumio sudo[882]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:07 sauls-volumio sudo[882]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:07 sauls-volumio sudo[882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 09 05:40:07 sauls-volumio sudo[882]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:07 sauls-volumio sudo[882]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:07 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:08 sauls-volumio wireless.js[576]: trying... Mar 09 05:40:08 sauls-volumio sudo[885]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:08 sauls-volumio sudo[885]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:08 sauls-volumio sudo[885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 09 05:40:08 sauls-volumio sudo[885]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:08 sauls-volumio sudo[885]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:08 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:09 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Fetching time from Volumio... Mar 09 05:40:09 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Date not found in response Mar 09 05:40:09 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Retrying in 5 seconds... Mar 09 05:40:09 sauls-volumio wireless.js[576]: trying... Mar 09 05:40:09 sauls-volumio sudo[893]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:09 sauls-volumio sudo[893]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:09 sauls-volumio sudo[893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 09 05:40:09 sauls-volumio sudo[893]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:09 sauls-volumio sudo[893]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:09 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:10 sauls-volumio wireless.js[576]: trying... Mar 09 05:40:10 sauls-volumio sudo[896]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:10 sauls-volumio sudo[896]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:10 sauls-volumio sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 09 05:40:10 sauls-volumio sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:10 sauls-volumio sudo[896]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:10 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:11 sauls-volumio wireless.js[576]: trying... Mar 09 05:40:11 sauls-volumio sudo[899]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:11 sauls-volumio sudo[899]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:11 sauls-volumio sudo[899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 09 05:40:11 sauls-volumio sudo[899]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:11 sauls-volumio sudo[899]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:11 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:12 sauls-volumio volumio-remote-updater[574]: [2026-03-09 05:40:12] [info] asio async_connect error: system:111 (Connection refused) Mar 09 05:40:12 sauls-volumio volumio-remote-updater[574]: [2026-03-09 05:40:12] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 09 05:40:12 sauls-volumio volumio-remote-updater[574]: [2026-03-09 05:40:12] [error] handle_connect error: Underlying Transport Error Mar 09 05:40:12 sauls-volumio wireless.js[576]: trying... Mar 09 05:40:12 sauls-volumio sudo[902]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:12 sauls-volumio sudo[902]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:12 sauls-volumio sudo[902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 09 05:40:12 sauls-volumio sudo[902]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:12 sauls-volumio sudo[902]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:12 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:13 sauls-volumio wireless.js[576]: trying... Mar 09 05:40:13 sauls-volumio sudo[905]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:13 sauls-volumio sudo[905]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:13 sauls-volumio sudo[905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 09 05:40:13 sauls-volumio sudo[905]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:13 sauls-volumio sudo[905]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:13 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:14 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Fetching time from Volumio... Mar 09 05:40:14 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Date not found in response Mar 09 05:40:14 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Retrying in 5 seconds... Mar 09 05:40:14 sauls-volumio wireless.js[576]: trying... Mar 09 05:40:14 sauls-volumio sudo[913]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:14 sauls-volumio sudo[913]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:14 sauls-volumio sudo[913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 09 05:40:14 sauls-volumio sudo[913]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:14 sauls-volumio sudo[913]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:14 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:15 sauls-volumio wireless.js[576]: trying... Mar 09 05:40:15 sauls-volumio sudo[916]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:15 sauls-volumio sudo[916]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:15 sauls-volumio sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 09 05:40:15 sauls-volumio sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:15 sauls-volumio sudo[916]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:15 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:16 sauls-volumio wireless.js[576]: trying... Mar 09 05:40:16 sauls-volumio sudo[919]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:16 sauls-volumio sudo[919]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:16 sauls-volumio sudo[919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 09 05:40:16 sauls-volumio sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:16 sauls-volumio sudo[919]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:16 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:17 sauls-volumio wireless.js[576]: trying... Mar 09 05:40:17 sauls-volumio sudo[922]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:17 sauls-volumio sudo[922]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:17 sauls-volumio sudo[922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 09 05:40:17 sauls-volumio sudo[922]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:17 sauls-volumio sudo[922]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:17 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:18 sauls-volumio wireless.js[576]: trying... Mar 09 05:40:18 sauls-volumio sudo[925]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:18 sauls-volumio sudo[925]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:18 sauls-volumio sudo[925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 09 05:40:18 sauls-volumio sudo[925]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:18 sauls-volumio sudo[925]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:18 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:19 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Fetching time from Volumio... Mar 09 05:40:19 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Date not found in response Mar 09 05:40:19 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Retrying in 5 seconds... Mar 09 05:40:19 sauls-volumio wireless.js[576]: trying... Mar 09 05:40:19 sauls-volumio sudo[933]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:19 sauls-volumio sudo[933]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:19 sauls-volumio sudo[933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 09 05:40:19 sauls-volumio sudo[933]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:19 sauls-volumio sudo[933]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:19 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:20 sauls-volumio wireless.js[576]: trying... Mar 09 05:40:20 sauls-volumio sudo[936]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:20 sauls-volumio sudo[936]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:20 sauls-volumio sudo[936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 09 05:40:20 sauls-volumio sudo[936]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:20 sauls-volumio sudo[936]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:20 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:21 sauls-volumio wireless.js[576]: trying... Mar 09 05:40:21 sauls-volumio sudo[939]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:21 sauls-volumio sudo[939]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:21 sauls-volumio sudo[939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 09 05:40:21 sauls-volumio sudo[939]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:21 sauls-volumio sudo[939]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:21 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:22 sauls-volumio volumio-remote-updater[574]: [2026-03-09 05:40:22] [info] asio async_connect error: system:111 (Connection refused) Mar 09 05:40:22 sauls-volumio volumio-remote-updater[574]: [2026-03-09 05:40:22] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 09 05:40:22 sauls-volumio volumio-remote-updater[574]: [2026-03-09 05:40:22] [error] handle_connect error: Underlying Transport Error Mar 09 05:40:22 sauls-volumio wireless.js[576]: trying... Mar 09 05:40:22 sauls-volumio sudo[942]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:22 sauls-volumio sudo[942]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:22 sauls-volumio sudo[942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 09 05:40:22 sauls-volumio sudo[942]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:22 sauls-volumio sudo[942]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:22 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:23 sauls-volumio wireless.js[576]: trying... Mar 09 05:40:23 sauls-volumio sudo[945]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:23 sauls-volumio sudo[945]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:23 sauls-volumio sudo[945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 09 05:40:23 sauls-volumio sudo[945]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:23 sauls-volumio sudo[945]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:23 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:24 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Fetching time from Volumio... Mar 09 05:40:24 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Date not found in response Mar 09 05:40:24 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Retrying in 5 seconds... Mar 09 05:40:24 sauls-volumio wireless.js[576]: trying... Mar 09 05:40:24 sauls-volumio sudo[953]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:24 sauls-volumio sudo[953]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:24 sauls-volumio sudo[953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 09 05:40:24 sauls-volumio sudo[953]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:24 sauls-volumio sudo[953]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:24 sauls-volumio wireless.js[576]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:25 sauls-volumio wireless.js[576]: Overtime, starting plan B Mar 09 05:40:25 sauls-volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 09 05:40:25 sauls-volumio systemd[1]: Started Wireless Services. Mar 09 05:40:25 sauls-volumio systemd[1]: Started Volumio Backend Module. Mar 09 05:40:25 sauls-volumio systemd[1]: Started Volumio Cpu Tweaker. Mar 09 05:40:25 sauls-volumio volumio-cpu-tweak[959]: Setting RT Priority for mpd Mar 09 05:40:25 sauls-volumio volumio-cpu-tweak[959]: pid 35's current scheduling policy: SCHED_OTHER Mar 09 05:40:25 sauls-volumio volumio-cpu-tweak[959]: pid 35's current scheduling priority: 0 Mar 09 05:40:26 sauls-volumio volumio-cpu-tweak[959]: Setting MPD Affinity Mar 09 05:40:26 sauls-volumio volumio-cpu-tweak[959]: pid 3's current affinity mask: f Mar 09 05:40:26 sauls-volumio volumio-cpu-tweak[959]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Mar 09 05:40:26 sauls-volumio volumio-cpu-tweak[959]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Mar 09 05:40:26 sauls-volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Mar 09 05:40:27 sauls-volumio volumio[958]: info: ------------------------------------------- Mar 09 05:40:27 sauls-volumio volumio[958]: info: ----- Volumio3 ---- Mar 09 05:40:27 sauls-volumio volumio[958]: info: ------------------------------------------- Mar 09 05:40:27 sauls-volumio volumio[958]: info: ----- System startup ---- Mar 09 05:40:27 sauls-volumio volumio[958]: info: ------------------------------------------- Mar 09 05:40:27 sauls-volumio systemd[1]: systemd-fsckd.service: Succeeded. Mar 09 05:40:28 sauls-volumio volumio[958]: info: MYVOLUMIO Environment detected Mar 09 05:40:28 sauls-volumio volumio[958]: info: Plugin folders cleanup Mar 09 05:40:28 sauls-volumio volumio[958]: info: Scanning into folder /volumio/app/plugins/ Mar 09 05:40:28 sauls-volumio volumio[958]: info: Scanning category audio_interface Mar 09 05:40:28 sauls-volumio volumio[958]: info: Scanning category miscellanea Mar 09 05:40:28 sauls-volumio volumio[958]: info: Scanning category music_service Mar 09 05:40:28 sauls-volumio volumio[958]: info: Scanning category plugins.json Mar 09 05:40:28 sauls-volumio volumio[958]: info: Scanning category system_controller Mar 09 05:40:28 sauls-volumio volumio[958]: info: Scanning category user_interface Mar 09 05:40:28 sauls-volumio volumio[958]: info: Scanning into folder /data/plugins/ Mar 09 05:40:28 sauls-volumio volumio[958]: info: Scanning category music_service Mar 09 05:40:28 sauls-volumio volumio[958]: info: Plugin folders cleanup completed Mar 09 05:40:28 sauls-volumio volumio[958]: info: ------------------------------------------- Mar 09 05:40:28 sauls-volumio volumio[958]: info: ----- Core plugins startup ---- Mar 09 05:40:28 sauls-volumio volumio[958]: info: ------------------------------------------- Mar 09 05:40:28 sauls-volumio volumio[958]: info: Loading plugins from folder /volumio/app/plugins/ Mar 09 05:40:28 sauls-volumio volumio[958]: info: Adding plugin upnp to MyMusic Plugins Mar 09 05:40:28 sauls-volumio volumio[958]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 09 05:40:28 sauls-volumio volumio[958]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 09 05:40:28 sauls-volumio volumio[958]: info: Loading plugins from folder /data/plugins/ Mar 09 05:40:28 sauls-volumio volumio[958]: info: Loading plugin "system"... Mar 09 05:40:28 sauls-volumio volumio[958]: info: Loading plugin "appearance"... Mar 09 05:40:28 sauls-volumio sh[500]: timed out Mar 09 05:40:28 sauls-volumio dhcpcd[532]: timed out Mar 09 05:40:28 sauls-volumio sh[500]: dhcpcd exited Mar 09 05:40:28 sauls-volumio dhcpcd[532]: dhcpcd exited Mar 09 05:40:28 sauls-volumio sh[500]: ifup: failed to bring up eth0 Mar 09 05:40:28 sauls-volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Mar 09 05:40:28 sauls-volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Mar 09 05:40:29 sauls-volumio volumio[958]: info: Loading plugin "network"... Mar 09 05:40:29 sauls-volumio volumio[958]: info: Refreshing Cached IP Addresses Mar 09 05:40:29 sauls-volumio sudo[990]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:29 sauls-volumio sudo[990]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:29 sauls-volumio sudo[990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 09 05:40:29 sauls-volumio sudo[990]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:29 sauls-volumio sudo[992]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:29 sauls-volumio sudo[992]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:29 sauls-volumio sudo[990]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:29 sauls-volumio sudo[992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 09 05:40:29 sauls-volumio sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:29 sauls-volumio volumio[958]: info: Loading plugin "services"... Mar 09 05:40:29 sauls-volumio sudo[992]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:29 sauls-volumio volumio[958]: info: Loading plugin "volumio5onboarding"... Mar 09 05:40:29 sauls-volumio sudo[1004]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:29 sauls-volumio sudo[1004]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:29 sauls-volumio sudo[1004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 09 05:40:29 sauls-volumio sudo[1004]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:29 sauls-volumio sudo[1004]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:29 sauls-volumio volumio[958]: info: Loading plugin "alsa_controller"... Mar 09 05:40:29 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Fetching time from Volumio... Mar 09 05:40:29 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Date not found in response Mar 09 05:40:29 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Retrying in 5 seconds... Mar 09 05:40:29 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 09 05:40:29 sauls-volumio volumio[958]: info: Loading plugin "wizard"... Mar 09 05:40:29 sauls-volumio volumio[958]: info: Loading plugin "networkfs"... Mar 09 05:40:29 sauls-volumio sudo[1023]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:29 sauls-volumio volumio[958]: info: Starting Udev Watcher for removable devices Mar 09 05:40:29 sauls-volumio sudo[1023]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:29 sauls-volumio sudo[1023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=zacd,password=aurCas22i^,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.11/Music /mnt/NAS/HelenusMusic Mar 09 05:40:29 sauls-volumio sudo[1023]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:29 sauls-volumio sudo[1025]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:29 sauls-volumio sudo[1025]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:29 sauls-volumio sudo[1025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=zacd,password=aurCas22i^,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.11/data/media/music /mnt/NAS/HelenusDataMusic Mar 09 05:40:29 sauls-volumio sudo[1025]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:29 sauls-volumio volumio[958]: info: Ignoring mount for partition: boot Mar 09 05:40:29 sauls-volumio volumio[958]: info: Ignoring mount for partition: volumio Mar 09 05:40:29 sauls-volumio volumio[958]: info: Ignoring mount for partition: volumio_data Mar 09 05:40:29 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 09 05:40:29 sauls-volumio volumio[958]: info: Loading plugin "volumio_command_line_client"... Mar 09 05:40:29 sauls-volumio volumio[958]: info: Loading plugin "upnp"... Mar 09 05:40:29 sauls-volumio volumio[958]: info: [1773034829795] Starting Upmpd Daemon Mar 09 05:40:29 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 09 05:40:29 sauls-volumio volumio[958]: info: Loading plugin "my_music"... Mar 09 05:40:29 sauls-volumio volumio[958]: info: Loading plugin "mpd"... Mar 09 05:40:29 sauls-volumio sudo[1023]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:29 sauls-volumio sudo[1025]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:29 sauls-volumio kernel: Key type cifs.spnego registered Mar 09 05:40:29 sauls-volumio kernel: Key type cifs.idmap registered Mar 09 05:40:29 sauls-volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Mar 09 05:40:29 sauls-volumio kernel: CIFS: Attempting to mount //192.168.0.11/data/media/music Mar 09 05:40:29 sauls-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 09 05:40:29 sauls-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Mar 09 05:40:29 sauls-volumio kernel: CIFS: Attempting to mount //192.168.0.11/Music Mar 09 05:40:29 sauls-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 09 05:40:29 sauls-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Mar 09 05:40:30 sauls-volumio volumio[958]: info: Loading plugin "upnp_browser"... Mar 09 05:40:30 sauls-volumio volumio[958]: info: Starting UPNP Browser Mar 09 05:40:30 sauls-volumio volumio[958]: info: Loading plugin "alarm-clock"... Mar 09 05:40:30 sauls-volumio volumio[958]: info: Loading plugin "airplay_emulation"... Mar 09 05:40:30 sauls-volumio volumio[958]: info: Starting Shairport Sync Mar 09 05:40:30 sauls-volumio volumio[958]: info: Loading plugin "last_100"... Mar 09 05:40:30 sauls-volumio volumio[958]: info: Loading plugin "webradio"... Mar 09 05:40:30 sauls-volumio volumio[958]: info: Loading plugin "i2s_dacs"... Mar 09 05:40:30 sauls-volumio volumio[958]: info: Loading plugin "volumiodiscovery"... Mar 09 05:40:30 sauls-volumio volumio[958]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 09 05:40:30 sauls-volumio volumio[958]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 09 05:40:30 sauls-volumio volumio[958]: *** WARNING *** For more information see Mar 09 05:40:30 sauls-volumio volumio[958]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 09 05:40:30 sauls-volumio volumio[958]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 09 05:40:30 sauls-volumio volumio[958]: *** WARNING *** For more information see Mar 09 05:40:30 sauls-volumio node[958]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 09 05:40:30 sauls-volumio node[958]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 09 05:40:30 sauls-volumio node[958]: *** WARNING *** For more information see Mar 09 05:40:30 sauls-volumio node[958]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 09 05:40:30 sauls-volumio node[958]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 09 05:40:30 sauls-volumio node[958]: *** WARNING *** For more information see Mar 09 05:40:30 sauls-volumio volumio[958]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 09 05:40:30 sauls-volumio volumio[958]: info: Discovery: Started advertising with name: Saul's Volumio Mar 09 05:40:30 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 09 05:40:30 sauls-volumio volumio[958]: info: Loading plugin "spop"... Mar 09 05:40:32 sauls-volumio volumio[958]: info: Loading plugin "squeezelite_mc"... Mar 09 05:40:32 sauls-volumio volumio-remote-updater[574]: [2026-03-09 05:40:32] [connect] Successful connection Mar 09 05:40:32 sauls-volumio volumio[958]: info: Loading plugin "outputs"... Mar 09 05:40:32 sauls-volumio volumio[958]: info: Loading plugin "albumart"... Mar 09 05:40:33 sauls-volumio volumio[958]: info: Plugin example_plugin is not enabled Mar 09 05:40:33 sauls-volumio volumio[958]: info: Loading plugin "inputs"... Mar 09 05:40:33 sauls-volumio volumio[958]: info: Loading plugin "updater_comm"... Mar 09 05:40:33 sauls-volumio volumio[958]: info: Plugin mpdemulation is not enabled Mar 09 05:40:33 sauls-volumio volumio[958]: info: Loading plugin "rest_api"... Mar 09 05:40:33 sauls-volumio volumio[958]: info: Loading plugin "websocket"... Mar 09 05:40:33 sauls-volumio volumio[958]: info: Starting Socket.io Server version 2.3.0 Mar 09 05:40:33 sauls-volumio volumio[958]: info: Loading plugin "RoonBridge"... Mar 09 05:40:33 sauls-volumio volumio[958]: Forking 3 albumart workers Mar 09 05:40:33 sauls-volumio wpa_supplicant[862]: wlan0: Failed to initiate sched scan Mar 09 05:40:33 sauls-volumio volumio[958]: info: Applying required configuration parameters for plugin RoonBridge Mar 09 05:40:33 sauls-volumio volumio[958]: info: Loading i18n strings for locale en Mar 09 05:40:33 sauls-volumio volumio[958]: Updating browse sources language Mar 09 05:40:33 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 05:40:33 sauls-volumio volumio[958]: Starting albumart workers Mar 09 05:40:33 sauls-volumio volumio[958]: Starting albumart workers Mar 09 05:40:33 sauls-volumio volumio[958]: Starting albumart workers Mar 09 05:40:33 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 05:40:33 sauls-volumio volumio[958]: info: CoreCommandRouter::initPlayerControls Mar 09 05:40:33 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:33 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:33 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:33 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:33 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:33 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:33 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:33 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:33 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 05:40:33 sauls-volumio volumio[958]: Express server listening on port 3000 Mar 09 05:40:33 sauls-volumio volumio[958]: [Metrics] WebUI: 6s 828.75ms Mar 09 05:40:33 sauls-volumio volumio[958]: info: CoreStateMachine::resetVolumioState Mar 09 05:40:33 sauls-volumio volumio[958]: info: CoreStateMachine::getcurrentVolume Mar 09 05:40:33 sauls-volumio volumio[958]: info: CoreCommandRouter::volumioRetrievevolume Mar 09 05:40:33 sauls-volumio volumio[958]: info: Volumio Network Manager: Network status updated: 0 Mar 09 05:40:33 sauls-volumio volumio[958]: info: Cannot mount NAS HelenusDataMusic at system boot, trial number 1 ,retrying in 5 seconds Mar 09 05:40:33 sauls-volumio volumio[958]: info: Cannot mount NAS HelenusMusic at system boot, trial number 1 ,retrying in 5 seconds Mar 09 05:40:33 sauls-volumio volumio[958]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 09 05:40:33 sauls-volumio volumio[958]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:33 sauls-volumio volumio[958]: wlan0 Interface doesn't support scanning : Device or resource busy Mar 09 05:40:33 sauls-volumio volumio[958]: info: Cannot use regular scanning, forcing with ap-force Mar 09 05:40:34 sauls-volumio sudo[1079]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:34 sauls-volumio sudo[1079]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:34 sauls-volumio sudo[1079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 09 05:40:34 sauls-volumio sudo[1079]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:34 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Fetching time from Volumio... Mar 09 05:40:34 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Date not found in response Mar 09 05:40:34 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Retrying in 5 seconds... Mar 09 05:40:36 sauls-volumio sudo[1079]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:36 sauls-volumio volumio[958]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:36 sauls-volumio volumio-remote-updater[574]: [2026-03-09 05:40:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1773034832 101 Mar 09 05:40:36 sauls-volumio volumio[958]: 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: 1 Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:40:36 sauls-volumio volumio[958]: info: Executing start script for DAC IQaudIO DAC Plus Mar 09 05:40:36 sauls-volumio volumio[958]: info: Reloading queue from file Mar 09 05:40:36 sauls-volumio volumio[958]: info: Setting Device type: Raspberry PI Mar 09 05:40:36 sauls-volumio volumio[958]: info: VolumeController:: Volume=100 Mute =false Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreStateMachine::pushState Mar 09 05:40:36 sauls-volumio volumio[958]: info: CorePlayQueue::getTrack 0 Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreCommandRouter::volumioPushState Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Mar 09 05:40:36 sauls-volumio volumio[958]: info: CorePlayQueue::getTrackBlock Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreCommandRouter::volumioRetrievevolume Mar 09 05:40:36 sauls-volumio volumio[958]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Mar 09 05:40:36 sauls-volumio volumio[958]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Mar 09 05:40:36 sauls-volumio volumio[958]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreStateMachine::setRepeat null single undefined Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreStateMachine::pushState Mar 09 05:40:36 sauls-volumio volumio[958]: info: CorePlayQueue::getTrack 0 Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreCommandRouter::volumioPushState Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreStateMachine::setRandom null Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreStateMachine::pushState Mar 09 05:40:36 sauls-volumio volumio[958]: info: CorePlayQueue::getTrack 0 Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreCommandRouter::volumioPushState Mar 09 05:40:36 sauls-volumio volumio[958]: info: DAC script executed Mar 09 05:40:36 sauls-volumio volumio[958]: info: Completed loading Core Plugins Mar 09 05:40:36 sauls-volumio volumio[958]: info: Preparing to generate the ALSA configuration file Mar 09 05:40:36 sauls-volumio volumio[958]: info: Asound.conf file unchanged, so no further update is needed Mar 09 05:40:36 sauls-volumio volumio[958]: info: Output device has changed, restarting MPD Mar 09 05:40:36 sauls-volumio sudo[1122]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:36 sauls-volumio sudo[1122]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:36 sauls-volumio volumio[958]: info: Output device has changed, restarting Shairport Sync Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:36 sauls-volumio sudo[1122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:40:36 sauls-volumio sudo[1122]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:36 sauls-volumio sudo[1124]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:36 sauls-volumio sudo[1124]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:36 sauls-volumio sudo[1122]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:36 sauls-volumio sudo[1124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 09 05:40:36 sauls-volumio sudo[1124]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:36 sauls-volumio volumio[958]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 09 05:40:36 sauls-volumio volumio[958]: info: ___________ START PLUGINS ___________ Mar 09 05:40:36 sauls-volumio volumio[958]: info: ControllerMpd::onStart: Initializing MPD Mar 09 05:40:36 sauls-volumio volumio[958]: info: Creating MPD Configuration file Mar 09 05:40:36 sauls-volumio sudo[1132]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:36 sauls-volumio sudo[1132]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:36 sauls-volumio sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Mar 09 05:40:36 sauls-volumio sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:36 sauls-volumio sudo[1134]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:36 sauls-volumio sudo[1134]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:36 sauls-volumio systemd[1]: Listening on mpd.socket. Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 09 05:40:36 sauls-volumio volumio[958]: info: [1773034836915] CoreMusicLibrary::Adding element Media Servers Mar 09 05:40:36 sauls-volumio sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 09 05:40:36 sauls-volumio systemd[1]: Starting Music Player Daemon... Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 05:40:36 sauls-volumio sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:36 sauls-volumio sudo[1134]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:36 sauls-volumio volumio[958]: info: UPNP Browser: Client initialized successfully Mar 09 05:40:36 sauls-volumio sudo[1137]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:36 sauls-volumio sudo[1137]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:40:36 sauls-volumio sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 09 05:40:36 sauls-volumio systemd[1]: Started Volumio5 Onboarding Server. Mar 09 05:40:36 sauls-volumio sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:36 sauls-volumio sudo[1132]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:36 sauls-volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 09 05:40:36 sauls-volumio systemd[1]: mpd.service: Succeeded. Mar 09 05:40:36 sauls-volumio systemd[1]: Stopped Music Player Daemon. Mar 09 05:40:36 sauls-volumio systemd[1]: Starting Music Player Daemon... Mar 09 05:40:36 sauls-volumio volumio[958]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:36 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:40:37 sauls-volumio volumio[958]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 09 05:40:37 sauls-volumio volumio[958]: info: [1773034837025] CoreMusicLibrary::Adding element Last_100 Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 09 05:40:37 sauls-volumio volumio[958]: info: [1773034837028] CoreMusicLibrary::Adding element Webradio Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 05:40:37 sauls-volumio sudo[1145]: root : unable to resolve host sauls-volumio Mar 09 05:40:37 sauls-volumio sudo[1145]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:37 sauls-volumio sudo[1145]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 09 05:40:37 sauls-volumio sudo[1145]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 09 05:40:37 sauls-volumio sudo[1145]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:37 sauls-volumio volumio[958]: info: Initializing BBC Radios Mar 09 05:40:37 sauls-volumio sudo[1145]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 09 05:40:37 sauls-volumio sudo[1145]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:40:37 sauls-volumio volumio[958]: info: Creating Spotify config file Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:37 sauls-volumio volumio[958]: info: [squeezelite_mc] Starting proxy server... Mar 09 05:40:37 sauls-volumio volumio[958]: info: Volumio Calling Home Mar 09 05:40:37 sauls-volumio sudo[1167]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:37 sauls-volumio sudo[1167]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:37 sauls-volumio sudo[1167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Mar 09 05:40:37 sauls-volumio sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:37 sauls-volumio volumio[958]: info: [squeezelite_mc] Proxy server started on port 46329 Mar 09 05:40:37 sauls-volumio systemd[1]: Started RoonBridge. Mar 09 05:40:37 sauls-volumio sudo[1167]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::volumioRetrievevolume Mar 09 05:40:37 sauls-volumio volumio[958]: Unhandled rejection Error: No sockets available, cannot start. Mar 09 05:40:37 sauls-volumio volumio[958]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Mar 09 05:40:37 sauls-volumio volumio[958]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Mar 09 05:40:37 sauls-volumio volumio[958]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Mar 09 05:40:37 sauls-volumio volumio[958]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Mar 09 05:40:37 sauls-volumio volumio[958]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Mar 09 05:40:37 sauls-volumio volumio[958]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Mar 09 05:40:37 sauls-volumio volumio[958]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Mar 09 05:40:37 sauls-volumio volumio[958]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Mar 09 05:40:37 sauls-volumio volumio[958]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) Mar 09 05:40:37 sauls-volumio volumio[958]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Mar 09 05:40:37 sauls-volumio volumio[958]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Mar 09 05:40:37 sauls-volumio volumio[958]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Mar 09 05:40:37 sauls-volumio volumio[958]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Mar 09 05:40:37 sauls-volumio volumio[958]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Mar 09 05:40:37 sauls-volumio volumio[958]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Mar 09 05:40:37 sauls-volumio volumio[958]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 09 05:40:37 sauls-volumio volumio[958]: info: MPD Permissions set Mar 09 05:40:37 sauls-volumio volumio[958]: info: VolumeController:: Volume=100 Mute =false Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreStateMachine::pushState Mar 09 05:40:37 sauls-volumio volumio[958]: info: CorePlayQueue::getTrack 0 Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::volumioPushState Mar 09 05:40:37 sauls-volumio volumio[958]: info: Spotify config file written Mar 09 05:40:37 sauls-volumio volumio[958]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Mar 09 05:40:37 sauls-volumio sudo[1183]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:37 sauls-volumio sudo[1183]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:37 sauls-volumio sudo[1183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 09 05:40:37 sauls-volumio sudo[1183]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:37 sauls-volumio volumio[958]: info: No need to fix Spotify hosts Mar 09 05:40:37 sauls-volumio volumio[958]: info: VolumeController:: Volume=100 Mute =false Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreStateMachine::pushState Mar 09 05:40:37 sauls-volumio volumio[958]: info: CorePlayQueue::getTrack 0 Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::volumioPushState Mar 09 05:40:37 sauls-volumio volumio[958]: info: [squeezelite_mc] Server discovery started Mar 09 05:40:37 sauls-volumio volumio[958]: info: [squeezelite_mc] Player finder started Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:37 sauls-volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 05:40:37 sauls-volumio volumio[958]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Mar 09 05:40:37 sauls-volumio volumio[958]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 05:40:37 sauls-volumio volumio[958]: Error: send ENETUNREACH 255.255.255.255:3483 Mar 09 05:40:37 sauls-volumio volumio[958]: at doSend (dgram.js:692:16) Mar 09 05:40:37 sauls-volumio volumio[958]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Mar 09 05:40:37 sauls-volumio volumio[958]: at afterDns (dgram.js:638:5) Mar 09 05:40:37 sauls-volumio volumio[958]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Mar 09 05:40:37 sauls-volumio volumio[958]: errno: -101, Mar 09 05:40:37 sauls-volumio volumio[958]: code: 'ENETUNREACH', Mar 09 05:40:37 sauls-volumio volumio[958]: syscall: 'send', Mar 09 05:40:37 sauls-volumio volumio[958]: address: '255.255.255.255', Mar 09 05:40:37 sauls-volumio volumio[958]: port: 3483 Mar 09 05:40:37 sauls-volumio volumio[958]: } Mar 09 05:40:37 sauls-volumio volumio[958]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 05:40:37 sauls-volumio systemd[1]: Started go-librespot Daemon. Mar 09 05:40:37 sauls-volumio go-librespot[1193]: Librespot-go daemon starting... Mar 09 05:40:37 sauls-volumio sudo[1183]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:37 sauls-volumio volumio5-onboarding[1140]: time=2026-03-09T05:40:37.685Z level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z Mar 09 05:40:37 sauls-volumio sudo[1216]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:37 sauls-volumio sudo[1216]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:37 sauls-volumio sudo[1216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-09 05:39 Mar 09 05:40:37 sauls-volumio sudo[1216]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:38 sauls-volumio sudo[1216]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:38 sauls-volumio volumio[958]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:38 sauls-volumio volumio-remote-updater[574]: [2026-03-09 05:40:38] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 09 05:40:38 sauls-volumio volumio-remote-updater[574]: [2026-03-09 05:40:38] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 09 05:40:38 sauls-volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 09 05:40:38 sauls-volumio volumio5-onboarding[1140]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:41902->127.0.0.1:3000: read: connection reset by peer Mar 09 05:40:38 sauls-volumio sudo[1137]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:38 sauls-volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 09 05:40:38 sauls-volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 09 05:40:38 sauls-volumio sudo[1124]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:38 sauls-volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 09 05:40:38 sauls-volumio systemd[1]: Started dynamicswap service. Mar 09 05:40:38 sauls-volumio systemd[1]: dynamicswap.service: Succeeded. Mar 09 05:40:38 sauls-volumio go-librespot[1193]: time="2026-03-09T05:40:38Z" level=info msg="generated new device id: 5e075c63e6ea316202721f5333143a6cf16b8141" Mar 09 05:40:38 sauls-volumio systemd[1]: volumio5-onboarding.service: Service RestartSec=100ms expired, scheduling restart. Mar 09 05:40:38 sauls-volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Mar 09 05:40:38 sauls-volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Mar 09 05:40:38 sauls-volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Mar 09 05:40:38 sauls-volumio systemd[1]: Stopped Volumio Backend Module. Mar 09 05:40:38 sauls-volumio systemd[1]: Started Volumio Backend Module. Mar 09 05:40:38 sauls-volumio systemd[1]: Started dynamicswap service. Mar 09 05:40:38 sauls-volumio systemd[1]: Stopped Volumio5 Onboarding Server. Mar 09 05:40:38 sauls-volumio systemd[1]: Started Volumio5 Onboarding Server. Mar 09 05:40:38 sauls-volumio go-librespot[1193]: time="2026-03-09T05:40:38Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 09 05:40:38 sauls-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 05:40:38 sauls-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 05:40:38 sauls-volumio systemd[1]: dynamicswap.service: Succeeded. Mar 09 05:40:38 sauls-volumio volumio5-onboarding[1232]: time=2026-03-09T05:40:38.355Z level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z Mar 09 05:40:38 sauls-volumio volumio5-onboarding[1232]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: dial tcp 127.0.0.1:3000: connect: connection refused Mar 09 05:40:38 sauls-volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 09 05:40:38 sauls-volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 09 05:40:38 sauls-volumio systemd[1]: volumio5-onboarding.service: Service RestartSec=100ms expired, scheduling restart. Mar 09 05:40:38 sauls-volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. Mar 09 05:40:38 sauls-volumio systemd[1]: Stopped Volumio5 Onboarding Server. Mar 09 05:40:38 sauls-volumio systemd[1]: Started Volumio5 Onboarding Server. Mar 09 05:40:38 sauls-volumio volumio5-onboarding[1251]: time=2026-03-09T05:40:38.636Z level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z Mar 09 05:40:38 sauls-volumio volumio5-onboarding[1251]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: dial tcp 127.0.0.1:3000: connect: connection refused Mar 09 05:40:38 sauls-volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 09 05:40:38 sauls-volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 09 05:40:38 sauls-volumio systemd[1]: volumio5-onboarding.service: Service RestartSec=100ms expired, scheduling restart. Mar 09 05:40:38 sauls-volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 3. Mar 09 05:40:38 sauls-volumio systemd[1]: Stopped Volumio5 Onboarding Server. Mar 09 05:40:38 sauls-volumio systemd[1]: Started Volumio5 Onboarding Server. Mar 09 05:40:38 sauls-volumio volumio5-onboarding[1258]: time=2026-03-09T05:40:38.889Z level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z Mar 09 05:40:38 sauls-volumio volumio5-onboarding[1258]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: dial tcp 127.0.0.1:3000: connect: connection refused Mar 09 05:40:38 sauls-volumio RoonBridge[1171]: 00:00:00.011 Warn: get lock file path: /tmp/.rnbgem0- Mar 09 05:40:38 sauls-volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 09 05:40:38 sauls-volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 09 05:40:39 sauls-volumio systemd[1]: volumio5-onboarding.service: Service RestartSec=100ms expired, scheduling restart. Mar 09 05:40:39 sauls-volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 4. Mar 09 05:40:39 sauls-volumio systemd[1]: Stopped Volumio5 Onboarding Server. Mar 09 05:40:39 sauls-volumio systemd[1]: Started Volumio5 Onboarding Server. Mar 09 05:40:39 sauls-volumio volumio5-onboarding[1267]: time=2026-03-09T05:40:39.140Z level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z Mar 09 05:40:39 sauls-volumio volumio5-onboarding[1267]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: dial tcp 127.0.0.1:3000: connect: connection refused Mar 09 05:40:39 sauls-volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 09 05:40:39 sauls-volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 09 05:40:39 sauls-volumio RoonBridge[1171]: 00:00:00.495 Trace: [childprocess] using unix child process Mar 09 05:40:39 sauls-volumio systemd[1]: volumio5-onboarding.service: Service RestartSec=100ms expired, scheduling restart. Mar 09 05:40:39 sauls-volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 5. Mar 09 05:40:39 sauls-volumio systemd[1]: Stopped Volumio5 Onboarding Server. Mar 09 05:40:39 sauls-volumio systemd[1]: volumio5-onboarding.service: Start request repeated too quickly. Mar 09 05:40:39 sauls-volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 09 05:40:39 sauls-volumio systemd[1]: Failed to start Volumio5 Onboarding Server. Mar 09 05:40:39 sauls-volumio RoonBridge[1171]: 00:00:00.578 Debug: PathForResource, filename: ../.update Mar 09 05:40:39 sauls-volumio RoonBridge[1171]: 00:00:00.653 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Mar 09 05:40:39 sauls-volumio RoonBridge[1171]: 00:00:00.662 Debug: PathForResource, filename: RAATServer Mar 09 05:40:39 sauls-volumio RoonBridge[1171]: 00:00:00.662 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Mar 09 05:40:39 sauls-volumio RoonBridge[1171]: 00:00:00.663 Debug: PathForResource, filename: RoonBridgeHelper Mar 09 05:40:39 sauls-volumio RoonBridge[1171]: 00:00:00.663 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Mar 09 05:40:39 sauls-volumio RoonBridge[1171]: Initializing Mar 09 05:40:39 sauls-volumio RoonBridge[1171]: 00:00:00.720 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Mar 09 05:40:39 sauls-volumio RoonBridge[1171]: 00:00:00.772 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Mar 09 05:40:39 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Fetching time from Volumio... Mar 09 05:40:39 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Date not found in response Mar 09 05:40:39 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Retrying in 5 seconds... Mar 09 05:40:39 sauls-volumio RoonBridge[1171]: Not Running (.o) Mar 09 05:40:39 sauls-volumio volumio[1230]: info: ------------------------------------------- Mar 09 05:40:39 sauls-volumio volumio[1230]: info: ----- Volumio3 ---- Mar 09 05:40:39 sauls-volumio volumio[1230]: info: ------------------------------------------- Mar 09 05:40:39 sauls-volumio volumio[1230]: info: ----- System startup ---- Mar 09 05:40:39 sauls-volumio volumio[1230]: info: ------------------------------------------- Mar 09 05:40:39 sauls-volumio RoonBridge[1171]: Running Mar 09 05:40:40 sauls-volumio mpd[1151]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 09 05:40:40 sauls-volumio mpd[1151]: output: No 'audio_output' defined in config file Mar 09 05:40:40 sauls-volumio mpd[1151]: output: Successfully detected a sndio audio device Mar 09 05:40:40 sauls-volumio mpd[1151]: zeroconf: No global port, disabling zeroconf Mar 09 05:40:40 sauls-volumio systemd[1]: Started Music Player Daemon. Mar 09 05:40:40 sauls-volumio volumio[1230]: info: MYVOLUMIO Environment detected Mar 09 05:40:40 sauls-volumio volumio[1230]: info: Plugin folders cleanup Mar 09 05:40:40 sauls-volumio volumio[1230]: info: Scanning into folder /volumio/app/plugins/ Mar 09 05:40:40 sauls-volumio volumio[1230]: info: Scanning category audio_interface Mar 09 05:40:40 sauls-volumio volumio[1230]: info: Scanning category miscellanea Mar 09 05:40:40 sauls-volumio volumio[1230]: info: Scanning category music_service Mar 09 05:40:40 sauls-volumio volumio[1230]: info: Scanning category plugins.json Mar 09 05:40:40 sauls-volumio volumio[1230]: info: Scanning category system_controller Mar 09 05:40:40 sauls-volumio volumio[1230]: info: Scanning category user_interface Mar 09 05:40:40 sauls-volumio volumio[1230]: info: Scanning into folder /data/plugins/ Mar 09 05:40:40 sauls-volumio volumio[1230]: info: Scanning category music_service Mar 09 05:40:40 sauls-volumio volumio[1230]: info: Plugin folders cleanup completed Mar 09 05:40:40 sauls-volumio volumio[1230]: info: ------------------------------------------- Mar 09 05:40:40 sauls-volumio volumio[1230]: info: ----- Core plugins startup ---- Mar 09 05:40:40 sauls-volumio volumio[1230]: info: ------------------------------------------- Mar 09 05:40:40 sauls-volumio volumio[1230]: info: Loading plugins from folder /volumio/app/plugins/ Mar 09 05:40:40 sauls-volumio volumio[1230]: info: Adding plugin upnp to MyMusic Plugins Mar 09 05:40:40 sauls-volumio volumio[1230]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 09 05:40:40 sauls-volumio volumio[1230]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 09 05:40:40 sauls-volumio volumio[1230]: info: Loading plugins from folder /data/plugins/ Mar 09 05:40:40 sauls-volumio volumio[1230]: info: Loading plugin "system"... Mar 09 05:40:40 sauls-volumio RoonBridge[1171]: 00:00:00.026 Warn: get lock file path: /tmp/.rnbhgem0- Mar 09 05:40:40 sauls-volumio volumio[1230]: info: Loading plugin "appearance"... Mar 09 05:40:41 sauls-volumio volumio[1230]: info: Loading plugin "network"... Mar 09 05:40:41 sauls-volumio volumio[1230]: info: Refreshing Cached IP Addresses Mar 09 05:40:41 sauls-volumio sudo[1325]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:41 sauls-volumio sudo[1327]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:41 sauls-volumio sudo[1325]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:41 sauls-volumio sudo[1327]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:41 sauls-volumio sudo[1325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 09 05:40:41 sauls-volumio sudo[1325]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:41 sauls-volumio sudo[1327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 09 05:40:41 sauls-volumio sudo[1327]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:41 sauls-volumio sudo[1325]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:41 sauls-volumio sudo[1327]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:41 sauls-volumio volumio[1230]: info: Loading plugin "services"... Mar 09 05:40:41 sauls-volumio volumio[1230]: info: Loading plugin "volumio5onboarding"... Mar 09 05:40:41 sauls-volumio sudo[1336]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:41 sauls-volumio sudo[1336]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:41 sauls-volumio sudo[1336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 09 05:40:41 sauls-volumio sudo[1336]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:41 sauls-volumio sudo[1336]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:41 sauls-volumio volumio[1230]: info: Loading plugin "alsa_controller"... Mar 09 05:40:41 sauls-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 05:40:41 sauls-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Mar 09 05:40:41 sauls-volumio systemd[1]: Stopped go-librespot Daemon. Mar 09 05:40:41 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 09 05:40:41 sauls-volumio volumio[1230]: info: Loading plugin "wizard"... Mar 09 05:40:41 sauls-volumio volumio[1230]: info: Loading plugin "networkfs"... Mar 09 05:40:41 sauls-volumio systemd[1]: Started go-librespot Daemon. Mar 09 05:40:41 sauls-volumio volumio[1230]: info: Starting Udev Watcher for removable devices Mar 09 05:40:41 sauls-volumio go-librespot[1351]: Librespot-go daemon starting... Mar 09 05:40:41 sauls-volumio sudo[1355]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:41 sauls-volumio sudo[1355]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:41 sauls-volumio sudo[1355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=zacd,password=aurCas22i^,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.11/Music /mnt/NAS/HelenusMusic Mar 09 05:40:41 sauls-volumio sudo[1357]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:41 sauls-volumio sudo[1357]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:41 sauls-volumio sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:41 sauls-volumio go-librespot[1351]: time="2026-03-09T05:40:41Z" level=info msg="generated new device id: 0bb4acb7805345d893789b584ac94e65e594c48e" Mar 09 05:40:41 sauls-volumio go-librespot[1351]: time="2026-03-09T05:40:41Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 09 05:40:41 sauls-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 05:40:41 sauls-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 05:40:41 sauls-volumio sudo[1357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=zacd,password=aurCas22i^,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.11/data/media/music /mnt/NAS/HelenusDataMusic Mar 09 05:40:41 sauls-volumio sudo[1357]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:41 sauls-volumio sudo[1355]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:41 sauls-volumio kernel: CIFS: Attempting to mount //192.168.0.11/Music Mar 09 05:40:41 sauls-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 09 05:40:41 sauls-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Mar 09 05:40:41 sauls-volumio volumio[1230]: info: Ignoring mount for partition: boot Mar 09 05:40:41 sauls-volumio volumio[1230]: info: Ignoring mount for partition: volumio Mar 09 05:40:41 sauls-volumio volumio[1230]: info: Ignoring mount for partition: volumio_data Mar 09 05:40:41 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 09 05:40:41 sauls-volumio volumio[1230]: info: Loading plugin "volumio_command_line_client"... Mar 09 05:40:41 sauls-volumio volumio[1230]: info: Loading plugin "upnp"... Mar 09 05:40:41 sauls-volumio volumio[1230]: info: [1773034841701] Starting Upmpd Daemon Mar 09 05:40:41 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 09 05:40:41 sauls-volumio volumio[1230]: info: Loading plugin "my_music"... Mar 09 05:40:41 sauls-volumio volumio[1230]: info: Loading plugin "mpd"... Mar 09 05:40:41 sauls-volumio kernel: CIFS: Attempting to mount //192.168.0.11/data/media/music Mar 09 05:40:41 sauls-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 09 05:40:41 sauls-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Mar 09 05:40:41 sauls-volumio sudo[1357]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:42 sauls-volumio volumio[1230]: info: Loading plugin "upnp_browser"... Mar 09 05:40:42 sauls-volumio volumio[1230]: info: Starting UPNP Browser Mar 09 05:40:42 sauls-volumio volumio[1230]: info: Loading plugin "alarm-clock"... Mar 09 05:40:42 sauls-volumio volumio[1230]: info: Loading plugin "airplay_emulation"... Mar 09 05:40:42 sauls-volumio volumio[1230]: info: Starting Shairport Sync Mar 09 05:40:42 sauls-volumio volumio[1230]: info: Loading plugin "last_100"... Mar 09 05:40:42 sauls-volumio volumio[1230]: info: Loading plugin "webradio"... Mar 09 05:40:42 sauls-volumio volumio[1230]: info: Loading plugin "i2s_dacs"... Mar 09 05:40:42 sauls-volumio volumio[1230]: info: Loading plugin "volumiodiscovery"... Mar 09 05:40:42 sauls-volumio volumio[1230]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 09 05:40:42 sauls-volumio volumio[1230]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 09 05:40:42 sauls-volumio volumio[1230]: *** WARNING *** For more information see Mar 09 05:40:42 sauls-volumio volumio[1230]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 09 05:40:42 sauls-volumio node[1230]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 09 05:40:42 sauls-volumio volumio[1230]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 09 05:40:42 sauls-volumio volumio[1230]: *** WARNING *** For more information see Mar 09 05:40:42 sauls-volumio node[1230]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 09 05:40:42 sauls-volumio node[1230]: *** WARNING *** For more information see Mar 09 05:40:42 sauls-volumio node[1230]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 09 05:40:42 sauls-volumio node[1230]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 09 05:40:42 sauls-volumio node[1230]: *** WARNING *** For more information see Mar 09 05:40:42 sauls-volumio volumio[1230]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 09 05:40:42 sauls-volumio volumio[1230]: info: Discovery: Started advertising with name: Saul's Volumio Mar 09 05:40:42 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 09 05:40:42 sauls-volumio volumio[1230]: info: Loading plugin "spop"... Mar 09 05:40:43 sauls-volumio volumio-remote-updater[574]: [2026-03-09 05:40:43] [connect] Successful connection Mar 09 05:40:43 sauls-volumio volumio[1230]: info: Loading plugin "squeezelite_mc"... Mar 09 05:40:43 sauls-volumio volumio[1230]: info: Loading plugin "outputs"... Mar 09 05:40:43 sauls-volumio volumio[1230]: info: Loading plugin "albumart"... Mar 09 05:40:43 sauls-volumio volumio[1230]: info: Plugin example_plugin is not enabled Mar 09 05:40:43 sauls-volumio volumio[1230]: info: Loading plugin "inputs"... Mar 09 05:40:43 sauls-volumio volumio[1230]: info: Loading plugin "updater_comm"... Mar 09 05:40:43 sauls-volumio volumio[1230]: info: Plugin mpdemulation is not enabled Mar 09 05:40:43 sauls-volumio volumio[1230]: info: Loading plugin "rest_api"... Mar 09 05:40:43 sauls-volumio volumio[1230]: info: Loading plugin "websocket"... Mar 09 05:40:43 sauls-volumio volumio[1230]: info: Starting Socket.io Server version 2.3.0 Mar 09 05:40:43 sauls-volumio volumio[1230]: info: Loading plugin "RoonBridge"... Mar 09 05:40:43 sauls-volumio volumio[1230]: Forking 3 albumart workers Mar 09 05:40:44 sauls-volumio volumio[1230]: info: Applying required configuration parameters for plugin RoonBridge Mar 09 05:40:44 sauls-volumio volumio[1230]: info: Loading i18n strings for locale en Mar 09 05:40:44 sauls-volumio volumio[1230]: Updating browse sources language Mar 09 05:40:44 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 05:40:44 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 05:40:44 sauls-volumio volumio[1230]: info: CoreCommandRouter::initPlayerControls Mar 09 05:40:44 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:44 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:44 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:44 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:44 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:44 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:44 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:44 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:44 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 05:40:44 sauls-volumio volumio[1230]: Express server listening on port 3000 Mar 09 05:40:44 sauls-volumio volumio[1230]: [Metrics] WebUI: 5s 4.99ms Mar 09 05:40:44 sauls-volumio volumio[1230]: info: CoreStateMachine::resetVolumioState Mar 09 05:40:44 sauls-volumio volumio[1230]: info: CoreStateMachine::getcurrentVolume Mar 09 05:40:44 sauls-volumio volumio[1230]: info: CoreCommandRouter::volumioRetrievevolume Mar 09 05:40:44 sauls-volumio volumio[1230]: info: Volumio Network Manager: Network status updated: 0 Mar 09 05:40:44 sauls-volumio volumio[1230]: info: Cannot mount NAS HelenusDataMusic at system boot, trial number 1 ,retrying in 5 seconds Mar 09 05:40:44 sauls-volumio volumio[1230]: info: Cannot mount NAS HelenusMusic at system boot, trial number 1 ,retrying in 5 seconds Mar 09 05:40:44 sauls-volumio volumio[1230]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 09 05:40:44 sauls-volumio volumio[1230]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:44 sauls-volumio volumio[1230]: wlan0 Interface doesn't support scanning : Device or resource busy Mar 09 05:40:44 sauls-volumio volumio[1230]: info: Cannot use regular scanning, forcing with ap-force Mar 09 05:40:44 sauls-volumio sudo[1423]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:44 sauls-volumio sudo[1423]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:44 sauls-volumio sudo[1423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 09 05:40:44 sauls-volumio sudo[1423]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:44 sauls-volumio volumio[1230]: Starting albumart workers Mar 09 05:40:44 sauls-volumio volumio[1230]: Starting albumart workers Mar 09 05:40:44 sauls-volumio volumio[1230]: Starting albumart workers Mar 09 05:40:44 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Fetching time from Volumio... Mar 09 05:40:44 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Date not found in response Mar 09 05:40:44 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Retrying in 5 seconds... Mar 09 05:40:44 sauls-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 05:40:44 sauls-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Mar 09 05:40:44 sauls-volumio systemd[1]: Stopped go-librespot Daemon. Mar 09 05:40:44 sauls-volumio systemd[1]: Started go-librespot Daemon. Mar 09 05:40:44 sauls-volumio go-librespot[1430]: Librespot-go daemon starting... Mar 09 05:40:44 sauls-volumio go-librespot[1430]: time="2026-03-09T05:40:44Z" level=info msg="generated new device id: 272793bb62b0cfd758e6dac66a83832fa68fc226" Mar 09 05:40:44 sauls-volumio go-librespot[1430]: time="2026-03-09T05:40:44Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 09 05:40:44 sauls-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 05:40:44 sauls-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 05:40:48 sauls-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 05:40:48 sauls-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Mar 09 05:40:48 sauls-volumio systemd[1]: Stopped go-librespot Daemon. Mar 09 05:40:48 sauls-volumio systemd[1]: Started go-librespot Daemon. Mar 09 05:40:48 sauls-volumio go-librespot[1462]: Librespot-go daemon starting... Mar 09 05:40:48 sauls-volumio go-librespot[1462]: time="2026-03-09T05:40:48Z" level=info msg="generated new device id: 1d33fb434bfc20bf5efbaf7fdded8a21074a79ca" Mar 09 05:40:48 sauls-volumio go-librespot[1462]: time="2026-03-09T05:40:48Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 09 05:40:48 sauls-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 05:40:48 sauls-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 05:40:49 sauls-volumio sudo[1423]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:49 sauls-volumio volumio[1230]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:40:49 sauls-volumio volumio[1230]: info: Executing start script for DAC IQaudIO DAC Plus Mar 09 05:40:49 sauls-volumio volumio[1230]: info: Reloading queue from file Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreStateMachine::setRepeat null single undefined Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreStateMachine::pushState Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreStateMachine::setRandom null Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreStateMachine::pushState Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Mar 09 05:40:49 sauls-volumio volumio[1230]: info: Setting Device type: Raspberry PI Mar 09 05:40:49 sauls-volumio volumio[1230]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Mar 09 05:40:49 sauls-volumio volumio[1230]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Mar 09 05:40:49 sauls-volumio volumio[1230]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Mar 09 05:40:49 sauls-volumio volumio[1230]: info: VolumeController:: Volume=100 Mute =false Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreStateMachine::pushState Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreStateMachine::updateTrackBlock Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CorePlayQueue::getTrackBlock Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::volumioRetrievevolume Mar 09 05:40:49 sauls-volumio volumio[1230]: info: DAC script executed Mar 09 05:40:49 sauls-volumio volumio[1230]: info: Completed loading Core Plugins Mar 09 05:40:49 sauls-volumio volumio[1230]: info: Preparing to generate the ALSA configuration file Mar 09 05:40:49 sauls-volumio volumio[1230]: info: Asound.conf file unchanged, so no further update is needed Mar 09 05:40:49 sauls-volumio volumio[1230]: info: Output device has changed, restarting MPD Mar 09 05:40:49 sauls-volumio sudo[1481]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:49 sauls-volumio sudo[1481]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:49 sauls-volumio volumio[1230]: info: Output device has changed, restarting Shairport Sync Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:40:49 sauls-volumio sudo[1481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 09 05:40:49 sauls-volumio sudo[1481]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:49 sauls-volumio sudo[1481]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:49 sauls-volumio sudo[1483]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:49 sauls-volumio sudo[1483]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:49 sauls-volumio sudo[1483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 09 05:40:49 sauls-volumio sudo[1483]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:49 sauls-volumio systemd[1]: Stopping Music Player Daemon... Mar 09 05:40:49 sauls-volumio volumio[1230]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 09 05:40:49 sauls-volumio volumio[1230]: info: ___________ START PLUGINS ___________ Mar 09 05:40:49 sauls-volumio volumio[1230]: info: ControllerMpd::onStart: Initializing MPD Mar 09 05:40:49 sauls-volumio volumio[1230]: info: Creating MPD Configuration file Mar 09 05:40:49 sauls-volumio sudo[1490]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:49 sauls-volumio sudo[1490]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:49 sauls-volumio sudo[1490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Mar 09 05:40:49 sauls-volumio sudo[1492]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:49 sauls-volumio sudo[1492]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:49 sauls-volumio sudo[1490]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 09 05:40:49 sauls-volumio volumio[1230]: info: [1773034849293] CoreMusicLibrary::Adding element Media Servers Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 05:40:49 sauls-volumio sudo[1492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 09 05:40:49 sauls-volumio sudo[1492]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:49 sauls-volumio sudo[1492]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:49 sauls-volumio volumio[1230]: info: UPNP Browser: Client initialized successfully Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:40:49 sauls-volumio sudo[1494]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:49 sauls-volumio sudo[1494]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:49 sauls-volumio volumio[1230]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:40:49 sauls-volumio sudo[1494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 09 05:40:49 sauls-volumio sudo[1494]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:49 sauls-volumio systemd[1]: Started Volumio5 Onboarding Server. Mar 09 05:40:49 sauls-volumio volumio[1230]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 09 05:40:49 sauls-volumio volumio[1230]: info: [1773034849375] CoreMusicLibrary::Adding element Last_100 Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 09 05:40:49 sauls-volumio volumio[1230]: info: [1773034849378] CoreMusicLibrary::Adding element Webradio Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 09 05:40:49 sauls-volumio sudo[1490]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:49 sauls-volumio volumio[1230]: info: Initializing BBC Radios Mar 09 05:40:49 sauls-volumio volumio5-onboarding[1497]: time=2026-03-09T05:40:49.397Z level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z Mar 09 05:40:49 sauls-volumio systemd[1]: mpd.service: Succeeded. Mar 09 05:40:49 sauls-volumio systemd[1]: Stopped Music Player Daemon. Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:40:49 sauls-volumio systemd[1]: Starting Music Player Daemon... Mar 09 05:40:49 sauls-volumio volumio[1230]: info: Creating Spotify config file Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:49 sauls-volumio volumio[1230]: info: [squeezelite_mc] Starting proxy server... Mar 09 05:40:49 sauls-volumio volumio[1230]: info: Volumio Calling Home Mar 09 05:40:49 sauls-volumio sudo[1523]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:49 sauls-volumio sudo[1523]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:49 sauls-volumio sudo[1523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Mar 09 05:40:49 sauls-volumio sudo[1523]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:49 sauls-volumio sudo[1521]: root : unable to resolve host sauls-volumio Mar 09 05:40:49 sauls-volumio sudo[1521]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:49 sauls-volumio sudo[1521]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:49 sauls-volumio sudo[1521]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 09 05:40:49 sauls-volumio sudo[1521]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:49 sauls-volumio sudo[1521]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 09 05:40:49 sauls-volumio sudo[1521]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:49 sauls-volumio sudo[1523]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:49 sauls-volumio volumio[1230]: info: [squeezelite_mc] Proxy server started on port 34655 Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::volumioRetrievevolume Mar 09 05:40:49 sauls-volumio sudo[1532]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:49 sauls-volumio sudo[1532]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:49 sauls-volumio sudo[1532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=zacd,password=aurCas22i^,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.11/data/media/music /mnt/NAS/HelenusDataMusic Mar 09 05:40:49 sauls-volumio sudo[1532]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:49 sauls-volumio volumio[1230]: Unhandled rejection Error: No sockets available, cannot start. Mar 09 05:40:49 sauls-volumio volumio[1230]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Mar 09 05:40:49 sauls-volumio volumio[1230]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Mar 09 05:40:49 sauls-volumio volumio[1230]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Mar 09 05:40:49 sauls-volumio volumio[1230]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Mar 09 05:40:49 sauls-volumio volumio[1230]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Mar 09 05:40:49 sauls-volumio volumio[1230]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Mar 09 05:40:49 sauls-volumio volumio[1230]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Mar 09 05:40:49 sauls-volumio volumio[1230]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Mar 09 05:40:49 sauls-volumio volumio[1230]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) Mar 09 05:40:49 sauls-volumio volumio[1230]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Mar 09 05:40:49 sauls-volumio volumio[1230]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Mar 09 05:40:49 sauls-volumio volumio[1230]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Mar 09 05:40:49 sauls-volumio volumio[1230]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Mar 09 05:40:49 sauls-volumio volumio[1230]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Mar 09 05:40:49 sauls-volumio volumio[1230]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Mar 09 05:40:49 sauls-volumio volumio[1230]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 09 05:40:49 sauls-volumio sudo[1532]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:49 sauls-volumio sudo[1534]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:49 sauls-volumio sudo[1534]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:49 sauls-volumio sudo[1534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=zacd,password=aurCas22i^,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.11/Music /mnt/NAS/HelenusMusic Mar 09 05:40:49 sauls-volumio sudo[1534]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:49 sauls-volumio kernel: CIFS: Attempting to mount //192.168.0.11/data/media/music Mar 09 05:40:49 sauls-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 09 05:40:49 sauls-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Mar 09 05:40:49 sauls-volumio sudo[1534]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:49 sauls-volumio kernel: CIFS: Attempting to mount //192.168.0.11/Music Mar 09 05:40:49 sauls-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 09 05:40:49 sauls-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Mar 09 05:40:49 sauls-volumio volumio[1230]: info: MPD Permissions set Mar 09 05:40:49 sauls-volumio volumio[1230]: info: MPD Permissions set Mar 09 05:40:49 sauls-volumio volumio[1230]: info: VolumeController:: Volume=100 Mute =false Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreStateMachine::pushState Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Mar 09 05:40:49 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Fetching time from Volumio... Mar 09 05:40:49 sauls-volumio volumio[1230]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Mar 09 05:40:49 sauls-volumio volumio[1230]: info: Cannot mount NAS HelenusMusic at system boot, trial number 2 ,retrying in 5 seconds Mar 09 05:40:49 sauls-volumio volumio[1230]: info: Cannot mount NAS HelenusDataMusic at system boot, trial number 2 ,retrying in 5 seconds Mar 09 05:40:49 sauls-volumio volumio[1230]: info: Spotify config file written Mar 09 05:40:49 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Date not found in response Mar 09 05:40:49 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Retrying in 5 seconds... Mar 09 05:40:49 sauls-volumio sudo[1551]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:49 sauls-volumio volumio[1230]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Mar 09 05:40:49 sauls-volumio sudo[1551]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:49 sauls-volumio volumio[1230]: info: Received Get System Info Mar 09 05:40:49 sauls-volumio sudo[1551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 09 05:40:49 sauls-volumio sudo[1551]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:49 sauls-volumio volumio[1230]: info: Discovery: Getting this device information Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 09 05:40:49 sauls-volumio volumio5-onboarding[1497]: time=2026-03-09T05:40:49.793Z level=INFO msg="system info for 6034a1782ad0af33a577a70973bdb6c3" deviceName="Saul's Volumio" deviceVariant=volumio deviceModel= softwareVersion=3.912 Mar 09 05:40:49 sauls-volumio systemd[1]: Stopped go-librespot Daemon. Mar 09 05:40:49 sauls-volumio volumio[1230]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Mar 09 05:40:49 sauls-volumio volumio[1230]: info: VolumeController:: Volume=100 Mute =false Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreStateMachine::pushState Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::volumioPushState Mar 09 05:40:49 sauls-volumio volumio[1230]: info: [squeezelite_mc] Server discovery started Mar 09 05:40:49 sauls-volumio volumio[1230]: info: [squeezelite_mc] Player finder started Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:49 sauls-volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 05:40:49 sauls-volumio volumio[1230]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Mar 09 05:40:49 sauls-volumio volumio[1230]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 05:40:49 sauls-volumio systemd[1]: Started go-librespot Daemon. Mar 09 05:40:49 sauls-volumio go-librespot[1553]: Librespot-go daemon starting... Mar 09 05:40:49 sauls-volumio sudo[1551]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:49 sauls-volumio volumio[1230]: Error: send ENETUNREACH 255.255.255.255:3483 Mar 09 05:40:49 sauls-volumio volumio[1230]: at doSend (dgram.js:692:16) Mar 09 05:40:49 sauls-volumio volumio[1230]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Mar 09 05:40:49 sauls-volumio volumio[1230]: at afterDns (dgram.js:638:5) Mar 09 05:40:49 sauls-volumio volumio[1230]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Mar 09 05:40:49 sauls-volumio volumio[1230]: errno: -101, Mar 09 05:40:49 sauls-volumio volumio[1230]: code: 'ENETUNREACH', Mar 09 05:40:49 sauls-volumio volumio[1230]: syscall: 'send', Mar 09 05:40:49 sauls-volumio volumio[1230]: address: '255.255.255.255', Mar 09 05:40:49 sauls-volumio volumio[1230]: port: 3483 Mar 09 05:40:49 sauls-volumio volumio[1230]: } Mar 09 05:40:49 sauls-volumio volumio[1230]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 05:40:49 sauls-volumio go-librespot[1553]: time="2026-03-09T05:40:49Z" level=info msg="generated new device id: fe5fc725d80203752ba964e73f80606249e280a9" Mar 09 05:40:49 sauls-volumio go-librespot[1553]: time="2026-03-09T05:40:49Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 09 05:40:49 sauls-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 05:40:49 sauls-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 05:40:50 sauls-volumio sudo[1571]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:50 sauls-volumio sudo[1571]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:50 sauls-volumio sudo[1571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-09 05:39 Mar 09 05:40:50 sauls-volumio sudo[1571]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:50 sauls-volumio sudo[1571]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:50 sauls-volumio mpd[1529]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 09 05:40:50 sauls-volumio mpd[1529]: output: No 'audio_output' defined in config file Mar 09 05:40:50 sauls-volumio mpd[1529]: output: Successfully detected a sndio audio device Mar 09 05:40:50 sauls-volumio mpd[1529]: zeroconf: No global port, disabling zeroconf Mar 09 05:40:50 sauls-volumio systemd[1]: Started Music Player Daemon. Mar 09 05:40:50 sauls-volumio sudo[1494]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:50 sauls-volumio sudo[1483]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:50 sauls-volumio volumio[1230]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:50 sauls-volumio volumio5-onboarding[1497]: time=2026-03-09T05:40:50.388Z level=ERROR msg="failed reading message" error="read tcp 127.0.0.1:41894->127.0.0.1:3000: read: connection reset by peer" Mar 09 05:40:50 sauls-volumio volumio5-onboarding[1497]: time=2026-03-09T05:40:50.389Z level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Mar 09 05:40:50 sauls-volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 09 05:40:50 sauls-volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 09 05:40:50 sauls-volumio systemd[1]: Started dynamicswap service. Mar 09 05:40:50 sauls-volumio systemd[1]: dynamicswap.service: Succeeded. Mar 09 05:40:50 sauls-volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Mar 09 05:40:50 sauls-volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Mar 09 05:40:50 sauls-volumio systemd[1]: Stopped Volumio Backend Module. Mar 09 05:40:50 sauls-volumio systemd[1]: Started Volumio Backend Module. Mar 09 05:40:50 sauls-volumio systemd[1]: Started dynamicswap service. Mar 09 05:40:50 sauls-volumio systemd[1]: dynamicswap.service: Succeeded. Mar 09 05:40:51 sauls-volumio volumio[1586]: info: ------------------------------------------- Mar 09 05:40:51 sauls-volumio volumio[1586]: info: ----- Volumio3 ---- Mar 09 05:40:51 sauls-volumio volumio[1586]: info: ------------------------------------------- Mar 09 05:40:51 sauls-volumio volumio[1586]: info: ----- System startup ---- Mar 09 05:40:51 sauls-volumio volumio[1586]: info: ------------------------------------------- Mar 09 05:40:51 sauls-volumio volumio[1586]: info: MYVOLUMIO Environment detected Mar 09 05:40:51 sauls-volumio volumio[1586]: info: Plugin folders cleanup Mar 09 05:40:51 sauls-volumio volumio[1586]: info: Scanning into folder /volumio/app/plugins/ Mar 09 05:40:51 sauls-volumio volumio[1586]: info: Scanning category audio_interface Mar 09 05:40:51 sauls-volumio volumio[1586]: info: Scanning category miscellanea Mar 09 05:40:51 sauls-volumio volumio[1586]: info: Scanning category music_service Mar 09 05:40:51 sauls-volumio volumio[1586]: info: Scanning category plugins.json Mar 09 05:40:51 sauls-volumio volumio[1586]: info: Scanning category system_controller Mar 09 05:40:51 sauls-volumio volumio[1586]: info: Scanning category user_interface Mar 09 05:40:51 sauls-volumio volumio[1586]: info: Scanning into folder /data/plugins/ Mar 09 05:40:51 sauls-volumio volumio[1586]: info: Scanning category music_service Mar 09 05:40:51 sauls-volumio volumio[1586]: info: Plugin folders cleanup completed Mar 09 05:40:51 sauls-volumio volumio[1586]: info: ------------------------------------------- Mar 09 05:40:51 sauls-volumio volumio[1586]: info: ----- Core plugins startup ---- Mar 09 05:40:51 sauls-volumio volumio[1586]: info: ------------------------------------------- Mar 09 05:40:51 sauls-volumio volumio[1586]: info: Loading plugins from folder /volumio/app/plugins/ Mar 09 05:40:51 sauls-volumio volumio[1586]: info: Adding plugin upnp to MyMusic Plugins Mar 09 05:40:51 sauls-volumio volumio[1586]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 09 05:40:51 sauls-volumio volumio[1586]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 09 05:40:51 sauls-volumio volumio[1586]: info: Loading plugins from folder /data/plugins/ Mar 09 05:40:51 sauls-volumio volumio[1586]: info: Loading plugin "system"... Mar 09 05:40:51 sauls-volumio volumio[1586]: info: Loading plugin "appearance"... Mar 09 05:40:52 sauls-volumio volumio[1586]: info: Loading plugin "network"... Mar 09 05:40:52 sauls-volumio volumio[1586]: info: Refreshing Cached IP Addresses Mar 09 05:40:52 sauls-volumio sudo[1613]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:52 sauls-volumio sudo[1613]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:52 sauls-volumio sudo[1613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 09 05:40:52 sauls-volumio sudo[1613]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:52 sauls-volumio sudo[1615]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:52 sauls-volumio sudo[1615]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:52 sauls-volumio sudo[1613]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:52 sauls-volumio sudo[1615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 09 05:40:52 sauls-volumio sudo[1615]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:52 sauls-volumio sudo[1615]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:52 sauls-volumio volumio[1586]: info: Loading plugin "services"... Mar 09 05:40:52 sauls-volumio volumio[1586]: info: Loading plugin "volumio5onboarding"... Mar 09 05:40:52 sauls-volumio sudo[1624]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:52 sauls-volumio sudo[1624]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:52 sauls-volumio sudo[1624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 09 05:40:52 sauls-volumio sudo[1624]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:52 sauls-volumio volumio[1586]: info: Loading plugin "alsa_controller"... Mar 09 05:40:52 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 09 05:40:52 sauls-volumio volumio[1586]: info: Loading plugin "wizard"... Mar 09 05:40:52 sauls-volumio volumio[1586]: info: Loading plugin "networkfs"... Mar 09 05:40:52 sauls-volumio wpa_supplicant[862]: wlan0: Trying to associate with SSID 'Epiphron' Mar 09 05:40:52 sauls-volumio sudo[1641]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:52 sauls-volumio sudo[1641]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:52 sauls-volumio volumio[1586]: info: Starting Udev Watcher for removable devices Mar 09 05:40:52 sauls-volumio sudo[1641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=zacd,password=aurCas22i^,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.11/Music /mnt/NAS/HelenusMusic Mar 09 05:40:52 sauls-volumio sudo[1641]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:52 sauls-volumio sudo[1643]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:52 sauls-volumio sudo[1643]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:52 sauls-volumio sudo[1643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=zacd,password=aurCas22i^,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.11/data/media/music /mnt/NAS/HelenusDataMusic Mar 09 05:40:52 sauls-volumio sudo[1641]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:52 sauls-volumio sudo[1643]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:52 sauls-volumio kernel: CIFS: Attempting to mount //192.168.0.11/Music Mar 09 05:40:52 sauls-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 09 05:40:52 sauls-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Mar 09 05:40:52 sauls-volumio volumio[1586]: info: Ignoring mount for partition: boot Mar 09 05:40:52 sauls-volumio volumio[1586]: info: Ignoring mount for partition: volumio Mar 09 05:40:52 sauls-volumio volumio[1586]: info: Ignoring mount for partition: volumio_data Mar 09 05:40:52 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 09 05:40:52 sauls-volumio volumio[1586]: info: Loading plugin "volumio_command_line_client"... Mar 09 05:40:52 sauls-volumio sudo[1643]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:52 sauls-volumio kernel: CIFS: Attempting to mount //192.168.0.11/data/media/music Mar 09 05:40:52 sauls-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 09 05:40:52 sauls-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Mar 09 05:40:52 sauls-volumio volumio[1586]: info: Loading plugin "upnp"... Mar 09 05:40:52 sauls-volumio volumio[1586]: info: [1773034852751] Starting Upmpd Daemon Mar 09 05:40:52 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 09 05:40:52 sauls-volumio volumio[1586]: info: Loading plugin "my_music"... Mar 09 05:40:52 sauls-volumio volumio[1586]: info: Loading plugin "mpd"... Mar 09 05:40:52 sauls-volumio dhcpcd[874]: wlan0: carrier acquired Mar 09 05:40:52 sauls-volumio wpa_supplicant[862]: wlan0: Associated with 3c:a6:2f:a4:5e:3a Mar 09 05:40:52 sauls-volumio wpa_supplicant[862]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:a6:2f:a4:5e:3a completed [id=0 id_str=] Mar 09 05:40:52 sauls-volumio wpa_supplicant[862]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 09 05:40:52 sauls-volumio wpa_supplicant[862]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=GB Mar 09 05:40:52 sauls-volumio dhcpcd[874]: DUID 00:01:00:01:2e:7c:57:96:e4:5f:01:02:c6:4d Mar 09 05:40:52 sauls-volumio dhcpcd[874]: wlan0: IAID 01:02:c6:4e Mar 09 05:40:52 sauls-volumio dhcpcd[874]: wlan0: adding address fe80::e65f:1ff:fe02:c64e Mar 09 05:40:52 sauls-volumio dhcpcd[874]: ipv6_addaddr1: Permission denied Mar 09 05:40:52 sauls-volumio dhcpcd[874]: wlan0: carrier lost Mar 09 05:40:52 sauls-volumio dhcpcd[874]: wlan0: carrier acquired Mar 09 05:40:52 sauls-volumio dhcpcd[874]: wlan0: connected to Access Point `Epiphron' Mar 09 05:40:52 sauls-volumio dhcpcd[874]: wlan0: IAID 01:02:c6:4e Mar 09 05:40:52 sauls-volumio sudo[1624]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:52 sauls-volumio volumio[1586]: info: Loading plugin "upnp_browser"... Mar 09 05:40:53 sauls-volumio dhcpcd[874]: wlan0: rebinding lease of 192.168.0.229 Mar 09 05:40:53 sauls-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 05:40:53 sauls-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Mar 09 05:40:53 sauls-volumio systemd[1]: Stopped go-librespot Daemon. Mar 09 05:40:53 sauls-volumio systemd[1]: Started go-librespot Daemon. Mar 09 05:40:53 sauls-volumio go-librespot[1663]: Librespot-go daemon starting... Mar 09 05:40:53 sauls-volumio go-librespot[1663]: time="2026-03-09T05:40:53Z" level=info msg="generated new device id: b3024d97f95f17ea8445715b9ed41394ab8dea04" Mar 09 05:40:53 sauls-volumio go-librespot[1663]: time="2026-03-09T05:40:53Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 09 05:40:53 sauls-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 05:40:53 sauls-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 05:40:53 sauls-volumio volumio[1586]: info: Starting UPNP Browser Mar 09 05:40:53 sauls-volumio volumio[1586]: info: Loading plugin "alarm-clock"... Mar 09 05:40:53 sauls-volumio dhcpcd[874]: wlan0: soliciting an IPv6 router Mar 09 05:40:53 sauls-volumio volumio[1586]: info: Loading plugin "airplay_emulation"... Mar 09 05:40:53 sauls-volumio volumio[1586]: info: Starting Shairport Sync Mar 09 05:40:53 sauls-volumio volumio[1586]: info: Loading plugin "last_100"... Mar 09 05:40:53 sauls-volumio volumio[1586]: info: Loading plugin "webradio"... Mar 09 05:40:53 sauls-volumio volumio[1586]: info: Loading plugin "i2s_dacs"... Mar 09 05:40:53 sauls-volumio volumio[1586]: info: Loading plugin "volumiodiscovery"... Mar 09 05:40:53 sauls-volumio volumio[1586]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 09 05:40:53 sauls-volumio volumio[1586]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 09 05:40:53 sauls-volumio node[1586]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 09 05:40:53 sauls-volumio volumio[1586]: *** WARNING *** For more information see Mar 09 05:40:53 sauls-volumio volumio[1586]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 09 05:40:53 sauls-volumio volumio[1586]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 09 05:40:53 sauls-volumio volumio[1586]: *** WARNING *** For more information see Mar 09 05:40:53 sauls-volumio node[1586]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 09 05:40:53 sauls-volumio node[1586]: *** WARNING *** For more information see Mar 09 05:40:53 sauls-volumio node[1586]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 09 05:40:53 sauls-volumio node[1586]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 09 05:40:53 sauls-volumio node[1586]: *** WARNING *** For more information see Mar 09 05:40:53 sauls-volumio volumio[1586]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 09 05:40:53 sauls-volumio volumio[1586]: info: Discovery: Started advertising with name: Saul's Volumio Mar 09 05:40:53 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 09 05:40:53 sauls-volumio volumio[1586]: info: Loading plugin "spop"... Mar 09 05:40:54 sauls-volumio volumio[1586]: info: Loading plugin "squeezelite_mc"... Mar 09 05:40:54 sauls-volumio volumio[1586]: info: Loading plugin "outputs"... Mar 09 05:40:54 sauls-volumio volumio[1586]: info: Loading plugin "albumart"... Mar 09 05:40:54 sauls-volumio volumio[1586]: info: Plugin example_plugin is not enabled Mar 09 05:40:54 sauls-volumio volumio[1586]: info: Loading plugin "inputs"... Mar 09 05:40:54 sauls-volumio volumio[1586]: info: Loading plugin "updater_comm"... Mar 09 05:40:54 sauls-volumio volumio[1586]: info: Plugin mpdemulation is not enabled Mar 09 05:40:54 sauls-volumio volumio[1586]: info: Loading plugin "rest_api"... Mar 09 05:40:54 sauls-volumio volumio[1586]: info: Loading plugin "websocket"... Mar 09 05:40:54 sauls-volumio volumio[1586]: info: Starting Socket.io Server version 2.3.0 Mar 09 05:40:54 sauls-volumio volumio[1586]: info: Loading plugin "RoonBridge"... Mar 09 05:40:54 sauls-volumio volumio[1586]: Forking 3 albumart workers Mar 09 05:40:54 sauls-volumio volumio[1586]: info: Applying required configuration parameters for plugin RoonBridge Mar 09 05:40:54 sauls-volumio volumio[1586]: info: Loading i18n strings for locale en Mar 09 05:40:54 sauls-volumio volumio[1586]: Updating browse sources language Mar 09 05:40:54 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 05:40:54 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Fetching time from Volumio... Mar 09 05:40:54 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Date not found in response Mar 09 05:40:54 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Retrying in 5 seconds... Mar 09 05:40:54 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 05:40:54 sauls-volumio volumio[1586]: info: CoreCommandRouter::initPlayerControls Mar 09 05:40:54 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:54 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:54 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:54 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:54 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:54 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:54 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:54 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:54 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 05:40:54 sauls-volumio volumio[1586]: Express server listening on port 3000 Mar 09 05:40:54 sauls-volumio volumio[1586]: [Metrics] WebUI: 3s 728.37ms Mar 09 05:40:54 sauls-volumio volumio[1586]: info: CoreStateMachine::resetVolumioState Mar 09 05:40:54 sauls-volumio volumio[1586]: info: CoreStateMachine::getcurrentVolume Mar 09 05:40:54 sauls-volumio volumio[1586]: info: CoreCommandRouter::volumioRetrievevolume Mar 09 05:40:55 sauls-volumio volumio[1586]: info: Volumio Network Manager: Network status updated: 0 Mar 09 05:40:55 sauls-volumio volumio[1586]: info: Cannot mount NAS HelenusDataMusic at system boot, trial number 1 ,retrying in 5 seconds Mar 09 05:40:55 sauls-volumio volumio[1586]: info: Cannot mount NAS HelenusMusic at system boot, trial number 1 ,retrying in 5 seconds Mar 09 05:40:55 sauls-volumio volumio[1586]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Mar 09 05:40:55 sauls-volumio volumio[1586]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:40:55 sauls-volumio volumio[1586]: info: Executing start script for DAC IQaudIO DAC Plus Mar 09 05:40:55 sauls-volumio volumio[1586]: info: Reloading queue from file Mar 09 05:40:55 sauls-volumio volumio[1586]: info: VolumeController:: Volume=100 Mute =false Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreStateMachine::pushState Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CorePlayQueue::getTrack 0 Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::volumioPushState Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreStateMachine::updateTrackBlock Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CorePlayQueue::getTrackBlock Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::volumioRetrievevolume Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreStateMachine::setRepeat null single undefined Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreStateMachine::pushState Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CorePlayQueue::getTrack 0 Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::volumioPushState Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreStateMachine::setRandom null Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreStateMachine::pushState Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CorePlayQueue::getTrack 0 Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::volumioPushState Mar 09 05:40:55 sauls-volumio volumio[1586]: Starting albumart workers Mar 09 05:40:55 sauls-volumio volumio[1586]: Starting albumart workers Mar 09 05:40:55 sauls-volumio volumio[1586]: info: Setting Device type: Raspberry PI Mar 09 05:40:55 sauls-volumio volumio[1586]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Mar 09 05:40:55 sauls-volumio volumio[1586]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Mar 09 05:40:55 sauls-volumio volumio[1586]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Mar 09 05:40:55 sauls-volumio volumio[1586]: info: DAC script executed Mar 09 05:40:55 sauls-volumio volumio[1586]: info: Completed loading Core Plugins Mar 09 05:40:55 sauls-volumio volumio[1586]: info: Preparing to generate the ALSA configuration file Mar 09 05:40:55 sauls-volumio volumio[1586]: Starting albumart workers Mar 09 05:40:55 sauls-volumio volumio[1586]: info: Asound.conf file unchanged, so no further update is needed Mar 09 05:40:55 sauls-volumio volumio[1586]: info: Output device has changed, restarting MPD Mar 09 05:40:55 sauls-volumio volumio[1586]: info: Output device has changed, restarting Shairport Sync Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:40:55 sauls-volumio sudo[1722]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:55 sauls-volumio sudo[1722]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:55 sauls-volumio sudo[1722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 09 05:40:55 sauls-volumio sudo[1724]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:55 sauls-volumio sudo[1724]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:55 sauls-volumio sudo[1722]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:55 sauls-volumio sudo[1724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 09 05:40:55 sauls-volumio sudo[1724]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:55 sauls-volumio sudo[1722]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:55 sauls-volumio systemd[1]: Stopping Music Player Daemon... Mar 09 05:40:55 sauls-volumio volumio[1586]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 09 05:40:55 sauls-volumio volumio[1586]: info: ___________ START PLUGINS ___________ Mar 09 05:40:55 sauls-volumio systemd[1]: mpd.service: Succeeded. Mar 09 05:40:55 sauls-volumio systemd[1]: Stopped Music Player Daemon. Mar 09 05:40:55 sauls-volumio volumio[1586]: info: ControllerMpd::onStart: Initializing MPD Mar 09 05:40:55 sauls-volumio volumio[1586]: info: Creating MPD Configuration file Mar 09 05:40:55 sauls-volumio sudo[1732]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:55 sauls-volumio sudo[1732]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:55 sauls-volumio sudo[1732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Mar 09 05:40:55 sauls-volumio sudo[1732]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:55 sauls-volumio systemd[1]: Starting Music Player Daemon... Mar 09 05:40:55 sauls-volumio sudo[1735]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:55 sauls-volumio sudo[1735]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:55 sauls-volumio sudo[1732]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:55 sauls-volumio sudo[1735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 09 05:40:55 sauls-volumio volumio[1586]: info: [1773034855564] CoreMusicLibrary::Adding element Media Servers Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 05:40:55 sauls-volumio sudo[1735]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:55 sauls-volumio sudo[1737]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:55 sauls-volumio sudo[1737]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:55 sauls-volumio volumio[1586]: info: UPNP Browser: Client initialized successfully Mar 09 05:40:55 sauls-volumio sudo[1737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 09 05:40:55 sauls-volumio sudo[1737]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:55 sauls-volumio sudo[1735]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:40:55 sauls-volumio volumio-remote-updater[574]: [2026-03-09 05:40:55] [connect] Successful connection Mar 09 05:40:55 sauls-volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 09 05:40:55 sauls-volumio systemd[1]: mpd.service: Succeeded. Mar 09 05:40:55 sauls-volumio systemd[1]: Stopped Music Player Daemon. Mar 09 05:40:55 sauls-volumio systemd[1]: Starting Music Player Daemon... Mar 09 05:40:55 sauls-volumio volumio[1586]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:40:55 sauls-volumio sudo[1741]: root : unable to resolve host sauls-volumio Mar 09 05:40:55 sauls-volumio sudo[1741]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:55 sauls-volumio sudo[1741]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:55 sauls-volumio sudo[1741]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 09 05:40:55 sauls-volumio sudo[1741]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:55 sauls-volumio sudo[1741]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 09 05:40:55 sauls-volumio sudo[1741]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:55 sauls-volumio volumio[1586]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 09 05:40:55 sauls-volumio volumio[1586]: info: [1773034855750] CoreMusicLibrary::Adding element Last_100 Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 09 05:40:55 sauls-volumio volumio[1586]: info: [1773034855752] CoreMusicLibrary::Adding element Webradio Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 09 05:40:55 sauls-volumio volumio[1586]: info: Initializing BBC Radios Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:40:55 sauls-volumio volumio[1586]: info: Creating Spotify config file Mar 09 05:40:55 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:55 sauls-volumio volumio[1586]: info: [squeezelite_mc] Starting proxy server... Mar 09 05:40:55 sauls-volumio volumio[1586]: info: Volumio Calling Home Mar 09 05:40:55 sauls-volumio sudo[1776]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:55 sauls-volumio sudo[1776]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:55 sauls-volumio sudo[1776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Mar 09 05:40:55 sauls-volumio sudo[1776]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:55 sauls-volumio sudo[1776]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:56 sauls-volumio volumio[1586]: info: [squeezelite_mc] Proxy server started on port 36807 Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::volumioRetrievevolume Mar 09 05:40:56 sauls-volumio volumio[1586]: Unhandled rejection Error: No sockets available, cannot start. Mar 09 05:40:56 sauls-volumio volumio[1586]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Mar 09 05:40:56 sauls-volumio volumio[1586]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Mar 09 05:40:56 sauls-volumio volumio[1586]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Mar 09 05:40:56 sauls-volumio volumio[1586]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Mar 09 05:40:56 sauls-volumio volumio[1586]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Mar 09 05:40:56 sauls-volumio volumio[1586]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Mar 09 05:40:56 sauls-volumio volumio[1586]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Mar 09 05:40:56 sauls-volumio volumio[1586]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Mar 09 05:40:56 sauls-volumio volumio[1586]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) Mar 09 05:40:56 sauls-volumio volumio[1586]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Mar 09 05:40:56 sauls-volumio volumio[1586]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Mar 09 05:40:56 sauls-volumio volumio[1586]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Mar 09 05:40:56 sauls-volumio volumio[1586]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Mar 09 05:40:56 sauls-volumio volumio[1586]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Mar 09 05:40:56 sauls-volumio volumio[1586]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Mar 09 05:40:56 sauls-volumio volumio[1586]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 09 05:40:56 sauls-volumio volumio[1586]: info: MPD Permissions set Mar 09 05:40:56 sauls-volumio volumio[1586]: info: MPD Permissions set Mar 09 05:40:56 sauls-volumio volumio[1586]: info: VolumeController:: Volume=100 Mute =false Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreStateMachine::pushState Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CorePlayQueue::getTrack 0 Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::volumioPushState Mar 09 05:40:56 sauls-volumio volumio-remote-updater[574]: [2026-03-09 05:40:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1773034855 101 Mar 09 05:40:56 sauls-volumio volumio[1586]: 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 Mar 09 05:40:56 sauls-volumio volumio[1586]: info: Spotify config file written Mar 09 05:40:56 sauls-volumio sudo[1792]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:56 sauls-volumio sudo[1792]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:56 sauls-volumio sudo[1792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 09 05:40:56 sauls-volumio volumio[1586]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Mar 09 05:40:56 sauls-volumio sudo[1792]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:56 sauls-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 05:40:56 sauls-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Mar 09 05:40:56 sauls-volumio systemd[1]: Stopped go-librespot Daemon. Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: No need to fix Spotify hosts Mar 09 05:40:56 sauls-volumio systemd[1]: Started go-librespot Daemon. Mar 09 05:40:56 sauls-volumio sudo[1792]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:56 sauls-volumio go-librespot[1797]: Librespot-go daemon starting... Mar 09 05:40:56 sauls-volumio volumio[1586]: info: VolumeController:: Volume=100 Mute =false Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreStateMachine::pushState Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CorePlayQueue::getTrack 0 Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::volumioPushState Mar 09 05:40:56 sauls-volumio go-librespot[1797]: time="2026-03-09T05:40:56Z" level=info msg="generated new device id: cee6f3426cb108279918d9670405998d07b16bdb" Mar 09 05:40:56 sauls-volumio go-librespot[1797]: time="2026-03-09T05:40:56Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 09 05:40:56 sauls-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 05:40:56 sauls-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 05:40:56 sauls-volumio volumio[1586]: info: [squeezelite_mc] Server discovery started Mar 09 05:40:56 sauls-volumio volumio[1586]: info: [squeezelite_mc] Player finder started Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 05:40:56 sauls-volumio volumio[1586]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Mar 09 05:40:56 sauls-volumio volumio[1586]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 05:40:56 sauls-volumio volumio[1586]: Error: send ENETUNREACH 255.255.255.255:3483 Mar 09 05:40:56 sauls-volumio volumio[1586]: at doSend (dgram.js:692:16) Mar 09 05:40:56 sauls-volumio volumio[1586]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Mar 09 05:40:56 sauls-volumio volumio[1586]: at afterDns (dgram.js:638:5) Mar 09 05:40:56 sauls-volumio volumio[1586]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Mar 09 05:40:56 sauls-volumio volumio[1586]: errno: -101, Mar 09 05:40:56 sauls-volumio volumio[1586]: code: 'ENETUNREACH', Mar 09 05:40:56 sauls-volumio volumio[1586]: syscall: 'send', Mar 09 05:40:56 sauls-volumio volumio[1586]: address: '255.255.255.255', Mar 09 05:40:56 sauls-volumio volumio[1586]: port: 3483 Mar 09 05:40:56 sauls-volumio volumio[1586]: } Mar 09 05:40:56 sauls-volumio volumio[1586]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 05:40:56 sauls-volumio mpd[1755]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 09 05:40:56 sauls-volumio mpd[1755]: output: No 'audio_output' defined in config file Mar 09 05:40:56 sauls-volumio mpd[1755]: output: Successfully detected a sndio audio device Mar 09 05:40:56 sauls-volumio mpd[1755]: zeroconf: No global port, disabling zeroconf Mar 09 05:40:56 sauls-volumio systemd[1]: Started Music Player Daemon. Mar 09 05:40:56 sauls-volumio sudo[1737]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:56 sauls-volumio sudo[1724]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:56 sauls-volumio sudo[1821]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:56 sauls-volumio sudo[1821]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:56 sauls-volumio sudo[1821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-09 05:39 Mar 09 05:40:56 sauls-volumio sudo[1821]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:56 sauls-volumio sudo[1821]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:56 sauls-volumio volumio[1586]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:40:56 sauls-volumio volumio5-onboarding[1497]: time=2026-03-09T05:40:56.840Z level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Mar 09 05:40:56 sauls-volumio volumio5-onboarding[1497]: time=2026-03-09T05:40:56.842Z level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Mar 09 05:40:56 sauls-volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 09 05:40:56 sauls-volumio volumio-remote-updater[574]: [2026-03-09 05:40:56] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 09 05:40:56 sauls-volumio volumio-remote-updater[574]: [2026-03-09 05:40:56] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 09 05:40:56 sauls-volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 09 05:40:56 sauls-volumio systemd[1]: Started dynamicswap service. Mar 09 05:40:56 sauls-volumio systemd[1]: dynamicswap.service: Succeeded. Mar 09 05:40:57 sauls-volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Mar 09 05:40:57 sauls-volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Mar 09 05:40:57 sauls-volumio systemd[1]: Started dynamicswap service. Mar 09 05:40:57 sauls-volumio systemd[1]: Stopped Volumio Backend Module. Mar 09 05:40:57 sauls-volumio systemd[1]: Started Volumio Backend Module. Mar 09 05:40:57 sauls-volumio systemd[1]: dynamicswap.service: Succeeded. Mar 09 05:40:58 sauls-volumio volumio[1835]: info: ------------------------------------------- Mar 09 05:40:58 sauls-volumio volumio[1835]: info: ----- Volumio3 ---- Mar 09 05:40:58 sauls-volumio volumio[1835]: info: ------------------------------------------- Mar 09 05:40:58 sauls-volumio volumio[1835]: info: ----- System startup ---- Mar 09 05:40:58 sauls-volumio volumio[1835]: info: ------------------------------------------- Mar 09 05:40:58 sauls-volumio dhcpcd[874]: wlan0: probing for an IPv4LL address Mar 09 05:40:58 sauls-volumio dhcpcd[874]: wlan0: DHCP lease expired Mar 09 05:40:58 sauls-volumio dhcpcd[874]: wlan0: soliciting a DHCP lease Mar 09 05:40:58 sauls-volumio volumio[1835]: info: MYVOLUMIO Environment detected Mar 09 05:40:58 sauls-volumio volumio[1835]: info: Plugin folders cleanup Mar 09 05:40:58 sauls-volumio volumio[1835]: info: Scanning into folder /volumio/app/plugins/ Mar 09 05:40:58 sauls-volumio volumio[1835]: info: Scanning category audio_interface Mar 09 05:40:58 sauls-volumio volumio[1835]: info: Scanning category miscellanea Mar 09 05:40:58 sauls-volumio volumio[1835]: info: Scanning category music_service Mar 09 05:40:58 sauls-volumio volumio[1835]: info: Scanning category plugins.json Mar 09 05:40:58 sauls-volumio volumio[1835]: info: Scanning category system_controller Mar 09 05:40:58 sauls-volumio volumio[1835]: info: Scanning category user_interface Mar 09 05:40:58 sauls-volumio volumio[1835]: info: Scanning into folder /data/plugins/ Mar 09 05:40:58 sauls-volumio volumio[1835]: info: Scanning category music_service Mar 09 05:40:58 sauls-volumio volumio[1835]: info: Plugin folders cleanup completed Mar 09 05:40:58 sauls-volumio volumio[1835]: info: ------------------------------------------- Mar 09 05:40:58 sauls-volumio volumio[1835]: info: ----- Core plugins startup ---- Mar 09 05:40:58 sauls-volumio volumio[1835]: info: ------------------------------------------- Mar 09 05:40:58 sauls-volumio volumio[1835]: info: Loading plugins from folder /volumio/app/plugins/ Mar 09 05:40:58 sauls-volumio volumio[1835]: info: Adding plugin upnp to MyMusic Plugins Mar 09 05:40:58 sauls-volumio volumio[1835]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 09 05:40:58 sauls-volumio volumio[1835]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 09 05:40:58 sauls-volumio volumio[1835]: info: Loading plugins from folder /data/plugins/ Mar 09 05:40:58 sauls-volumio volumio[1835]: info: Loading plugin "system"... Mar 09 05:40:58 sauls-volumio volumio[1835]: info: Loading plugin "appearance"... Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Loading plugin "network"... Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Refreshing Cached IP Addresses Mar 09 05:40:59 sauls-volumio sudo[1890]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:59 sauls-volumio sudo[1890]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:59 sauls-volumio sudo[1890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 09 05:40:59 sauls-volumio sudo[1890]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:59 sauls-volumio sudo[1892]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:59 sauls-volumio sudo[1892]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:59 sauls-volumio sudo[1890]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:59 sauls-volumio sudo[1892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 09 05:40:59 sauls-volumio sudo[1892]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Loading plugin "services"... Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Loading plugin "volumio5onboarding"... Mar 09 05:40:59 sauls-volumio sudo[1892]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:59 sauls-volumio sudo[1901]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:59 sauls-volumio sudo[1901]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Loading plugin "alsa_controller"... Mar 09 05:40:59 sauls-volumio sudo[1901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 09 05:40:59 sauls-volumio sudo[1901]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:59 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Loading plugin "wizard"... Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Loading plugin "networkfs"... Mar 09 05:40:59 sauls-volumio sudo[1918]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:59 sauls-volumio sudo[1918]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Starting Udev Watcher for removable devices Mar 09 05:40:59 sauls-volumio sudo[1918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=zacd,password=aurCas22i^,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.11/Music /mnt/NAS/HelenusMusic Mar 09 05:40:59 sauls-volumio sudo[1918]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:59 sauls-volumio sudo[1920]: volumio : unable to resolve host sauls-volumio Mar 09 05:40:59 sauls-volumio sudo[1920]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:40:59 sauls-volumio sudo[1920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=zacd,password=aurCas22i^,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.11/data/media/music /mnt/NAS/HelenusDataMusic Mar 09 05:40:59 sauls-volumio kernel: CIFS: Attempting to mount //192.168.0.11/Music Mar 09 05:40:59 sauls-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 09 05:40:59 sauls-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Mar 09 05:40:59 sauls-volumio sudo[1920]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:40:59 sauls-volumio sudo[1918]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Ignoring mount for partition: boot Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Ignoring mount for partition: volumio Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Ignoring mount for partition: volumio_data Mar 09 05:40:59 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Loading plugin "volumio_command_line_client"... Mar 09 05:40:59 sauls-volumio sudo[1920]: pam_unix(sudo:session): session closed for user root Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Loading plugin "upnp"... Mar 09 05:40:59 sauls-volumio volumio[1835]: info: [1773034859244] Starting Upmpd Daemon Mar 09 05:40:59 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Loading plugin "my_music"... Mar 09 05:40:59 sauls-volumio kernel: CIFS: Attempting to mount //192.168.0.11/data/media/music Mar 09 05:40:59 sauls-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 09 05:40:59 sauls-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Loading plugin "mpd"... Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Loading plugin "upnp_browser"... Mar 09 05:40:59 sauls-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 05:40:59 sauls-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Mar 09 05:40:59 sauls-volumio systemd[1]: Stopped go-librespot Daemon. Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Starting UPNP Browser Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Loading plugin "alarm-clock"... Mar 09 05:40:59 sauls-volumio systemd[1]: Started go-librespot Daemon. Mar 09 05:40:59 sauls-volumio go-librespot[1927]: Librespot-go daemon starting... Mar 09 05:40:59 sauls-volumio go-librespot[1927]: time="2026-03-09T05:40:59Z" level=info msg="generated new device id: c1d545965e34eb06350c4c6c2956a495f7b2ee4e" Mar 09 05:40:59 sauls-volumio go-librespot[1927]: time="2026-03-09T05:40:59Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 09 05:40:59 sauls-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 05:40:59 sauls-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Loading plugin "airplay_emulation"... Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Starting Shairport Sync Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Loading plugin "last_100"... Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Loading plugin "webradio"... Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Loading plugin "i2s_dacs"... Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Loading plugin "volumiodiscovery"... Mar 09 05:40:59 sauls-volumio volumio[1835]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 09 05:40:59 sauls-volumio volumio[1835]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 09 05:40:59 sauls-volumio volumio[1835]: *** WARNING *** For more information see Mar 09 05:40:59 sauls-volumio node[1835]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 09 05:40:59 sauls-volumio volumio[1835]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 09 05:40:59 sauls-volumio volumio[1835]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 09 05:40:59 sauls-volumio volumio[1835]: *** WARNING *** For more information see Mar 09 05:40:59 sauls-volumio node[1835]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 09 05:40:59 sauls-volumio node[1835]: *** WARNING *** For more information see Mar 09 05:40:59 sauls-volumio node[1835]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 09 05:40:59 sauls-volumio node[1835]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 09 05:40:59 sauls-volumio node[1835]: *** WARNING *** For more information see Mar 09 05:40:59 sauls-volumio volumio5-onboarding[1497]: failed to configure discovery: failed to get system info: could not get system info: context deadline exceeded Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 09 05:40:59 sauls-volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Discovery: Started advertising with name: Saul's Volumio Mar 09 05:40:59 sauls-volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 09 05:40:59 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 09 05:40:59 sauls-volumio volumio[1835]: info: Loading plugin "spop"... Mar 09 05:40:59 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Fetching time from Volumio... Mar 09 05:40:59 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Date not found in response Mar 09 05:40:59 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Retrying in 5 seconds... Mar 09 05:41:00 sauls-volumio systemd[1]: volumio5-onboarding.service: Service RestartSec=100ms expired, scheduling restart. Mar 09 05:41:00 sauls-volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Mar 09 05:41:00 sauls-volumio systemd[1]: Stopped Volumio5 Onboarding Server. Mar 09 05:41:00 sauls-volumio systemd[1]: Started Volumio5 Onboarding Server. Mar 09 05:41:00 sauls-volumio volumio5-onboarding[1941]: time=2026-03-09T05:41:00.158Z level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z Mar 09 05:41:00 sauls-volumio volumio[1835]: info: Loading plugin "squeezelite_mc"... Mar 09 05:41:00 sauls-volumio volumio[1835]: info: Loading plugin "outputs"... Mar 09 05:41:00 sauls-volumio volumio[1835]: info: Loading plugin "albumart"... Mar 09 05:41:00 sauls-volumio volumio[1835]: info: Plugin example_plugin is not enabled Mar 09 05:41:00 sauls-volumio volumio[1835]: info: Loading plugin "inputs"... Mar 09 05:41:00 sauls-volumio volumio[1835]: info: Loading plugin "updater_comm"... Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Plugin mpdemulation is not enabled Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Loading plugin "rest_api"... Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Loading plugin "websocket"... Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Starting Socket.io Server version 2.3.0 Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Loading plugin "RoonBridge"... Mar 09 05:41:01 sauls-volumio volumio[1835]: Forking 3 albumart workers Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Applying required configuration parameters for plugin RoonBridge Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Loading i18n strings for locale en Mar 09 05:41:01 sauls-volumio volumio[1835]: Updating browse sources language Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::initPlayerControls Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 05:41:01 sauls-volumio volumio[1835]: Express server listening on port 3000 Mar 09 05:41:01 sauls-volumio volumio[1835]: [Metrics] WebUI: 3s 690.04ms Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreStateMachine::resetVolumioState Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreStateMachine::getcurrentVolume Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::volumioRetrievevolume Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Volumio Network Manager: Network status updated: 0 Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Cannot mount NAS HelenusDataMusic at system boot, trial number 1 ,retrying in 5 seconds Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Cannot mount NAS HelenusMusic at system boot, trial number 1 ,retrying in 5 seconds Mar 09 05:41:01 sauls-volumio volumio[1835]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Mar 09 05:41:01 sauls-volumio volumio[1835]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Received Get System Info Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Discovery: Getting this device information Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::volumioGetState Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CorePlayQueue::getTrack 0 Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 09 05:41:01 sauls-volumio volumio5-onboarding[1941]: time=2026-03-09T05:41:01.620Z level=INFO msg="system info for 6034a1782ad0af33a577a70973bdb6c3" deviceName="Saul's Volumio" deviceVariant=volumio deviceModel= softwareVersion=3.912 Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Executing start script for DAC IQaudIO DAC Plus Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Reloading queue from file Mar 09 05:41:01 sauls-volumio volumio[1835]: info: VolumeController:: Volume=100 Mute =false Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreStateMachine::pushState Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CorePlayQueue::getTrack 0 Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::volumioPushState Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreStateMachine::updateTrackBlock Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CorePlayQueue::getTrackBlock Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::volumioRetrievevolume Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreStateMachine::setRepeat null single undefined Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreStateMachine::pushState Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CorePlayQueue::getTrack 0 Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::volumioPushState Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreStateMachine::setRandom null Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreStateMachine::pushState Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CorePlayQueue::getTrack 0 Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::volumioPushState Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Setting Device type: Raspberry PI Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Received Get System Info Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Discovery: Getting this device information Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::volumioGetState Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CorePlayQueue::getTrack 0 Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 09 05:41:01 sauls-volumio volumio[1835]: Starting albumart workers Mar 09 05:41:01 sauls-volumio volumio[1835]: info: DAC script executed Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Completed loading Core Plugins Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Preparing to generate the ALSA configuration file Mar 09 05:41:01 sauls-volumio volumio[1835]: Starting albumart workers Mar 09 05:41:01 sauls-volumio volumio[1835]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Mar 09 05:41:01 sauls-volumio volumio[1835]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Mar 09 05:41:01 sauls-volumio volumio[1835]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 09 05:41:01 sauls-volumio volumio5-onboarding[1941]: time=2026-03-09T05:41:01.813Z level=INFO msg="enabling local network discovery" Mar 09 05:41:01 sauls-volumio volumio[1835]: Starting albumart workers Mar 09 05:41:01 sauls-volumio volumio5-onboarding[1941]: time=2026-03-09T05:41:01.833Z level=INFO msg="enabling BLE discovery" Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Asound.conf file unchanged, so no further update is needed Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Output device has changed, restarting MPD Mar 09 05:41:01 sauls-volumio volumio-remote-updater[574]: [2026-03-09 05:41:01] [connect] Successful connection Mar 09 05:41:01 sauls-volumio volumio5-onboarding[1941]: time=2026-03-09T05:41:01.870Z level=INFO msg="bootstrapping state" hasInternet=false Mar 09 05:41:01 sauls-volumio volumio5-onboarding[1941]: time=2026-03-09T05:41:01.873Z level=INFO msg="service successfully established" component=discovery/localnet Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Output device has changed, restarting Shairport Sync Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:01 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:41:01 sauls-volumio sudo[1997]: volumio : unable to resolve host sauls-volumio Mar 09 05:41:01 sauls-volumio sudo[1997]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:41:01 sauls-volumio sudo[1997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 09 05:41:01 sauls-volumio sudo[1997]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:41:01 sauls-volumio sudo[1999]: volumio : unable to resolve host sauls-volumio Mar 09 05:41:01 sauls-volumio sudo[1999]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:41:01 sauls-volumio sudo[1999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 09 05:41:01 sauls-volumio sudo[1999]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:41:01 sauls-volumio sudo[1997]: pam_unix(sudo:session): session closed for user root Mar 09 05:41:01 sauls-volumio systemd[1]: Stopping Music Player Daemon... Mar 09 05:41:01 sauls-volumio volumio[1835]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 09 05:41:01 sauls-volumio volumio[1835]: info: ___________ START PLUGINS ___________ Mar 09 05:41:01 sauls-volumio systemd[1]: mpd.service: Succeeded. Mar 09 05:41:01 sauls-volumio systemd[1]: Stopped Music Player Daemon. Mar 09 05:41:02 sauls-volumio volumio[1835]: info: ControllerMpd::onStart: Initializing MPD Mar 09 05:41:02 sauls-volumio volumio[1835]: info: Creating MPD Configuration file Mar 09 05:41:02 sauls-volumio sudo[2007]: volumio : unable to resolve host sauls-volumio Mar 09 05:41:02 sauls-volumio sudo[2007]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:41:02 sauls-volumio systemd[1]: Starting Music Player Daemon... Mar 09 05:41:02 sauls-volumio sudo[2007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Mar 09 05:41:02 sauls-volumio sudo[2007]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 09 05:41:02 sauls-volumio volumio[1835]: info: [1773034862061] CoreMusicLibrary::Adding element Media Servers Mar 09 05:41:02 sauls-volumio sudo[2009]: volumio : unable to resolve host sauls-volumio Mar 09 05:41:02 sauls-volumio sudo[2009]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 05:41:02 sauls-volumio volumio[1835]: info: UPNP Browser: Client initialized successfully Mar 09 05:41:02 sauls-volumio sudo[2009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 09 05:41:02 sauls-volumio sudo[2009]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:02 sauls-volumio sudo[2009]: pam_unix(sudo:session): session closed for user root Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:41:02 sauls-volumio sudo[2011]: volumio : unable to resolve host sauls-volumio Mar 09 05:41:02 sauls-volumio sudo[2011]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:41:02 sauls-volumio sudo[2011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 09 05:41:02 sauls-volumio sudo[2011]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:41:02 sauls-volumio sudo[2007]: pam_unix(sudo:session): session closed for user root Mar 09 05:41:02 sauls-volumio sudo[2006]: root : unable to resolve host sauls-volumio Mar 09 05:41:02 sauls-volumio sudo[2006]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:41:02 sauls-volumio sudo[2006]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:41:02 sauls-volumio sudo[2006]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 09 05:41:02 sauls-volumio sudo[2006]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:41:02 sauls-volumio sudo[2006]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 09 05:41:02 sauls-volumio sudo[2006]: pam_unix(sudo:session): session closed for user root Mar 09 05:41:02 sauls-volumio systemd[1]: mpd.service: Succeeded. Mar 09 05:41:02 sauls-volumio systemd[1]: Stopped Music Player Daemon. Mar 09 05:41:02 sauls-volumio volumio[1835]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:41:02 sauls-volumio systemd[1]: Starting Music Player Daemon... Mar 09 05:41:02 sauls-volumio sudo[2019]: root : unable to resolve host sauls-volumio Mar 09 05:41:02 sauls-volumio sudo[2019]: sudo: unable to resolve host sauls-volumio: System error Mar 09 05:41:02 sauls-volumio sudo[2019]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:41:02 sauls-volumio sudo[2019]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 09 05:41:02 sauls-volumio volumio[1835]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 09 05:41:02 sauls-volumio sudo[2019]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 09 05:41:02 sauls-volumio volumio[1835]: info: [1773034862303] CoreMusicLibrary::Adding element Last_100 Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 05:41:02 sauls-volumio sudo[2019]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 09 05:41:02 sauls-volumio sudo[2019]: pam_unix(sudo:session): session closed for user root Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 09 05:41:02 sauls-volumio volumio[1835]: info: [1773034862312] CoreMusicLibrary::Adding element Webradio Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 09 05:41:02 sauls-volumio volumio[1835]: info: Initializing BBC Radios Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: Creating Spotify config file Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: [squeezelite_mc] Starting proxy server... Mar 09 05:41:02 sauls-volumio volumio[1835]: info: Volumio Calling Home Mar 09 05:41:02 sauls-volumio sudo[2060]: volumio : unable to resolve host sauls-volumio Mar 09 05:41:02 sauls-volumio sudo[2060]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:41:02 sauls-volumio sudo[2060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Mar 09 05:41:02 sauls-volumio sudo[2060]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:41:02 sauls-volumio sudo[2060]: pam_unix(sudo:session): session closed for user root Mar 09 05:41:02 sauls-volumio volumio[1835]: info: [squeezelite_mc] Proxy server started on port 36603 Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::volumioRetrievevolume Mar 09 05:41:02 sauls-volumio volumio[1835]: Unhandled rejection Error: No sockets available, cannot start. Mar 09 05:41:02 sauls-volumio volumio[1835]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Mar 09 05:41:02 sauls-volumio volumio[1835]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Mar 09 05:41:02 sauls-volumio volumio[1835]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Mar 09 05:41:02 sauls-volumio volumio[1835]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Mar 09 05:41:02 sauls-volumio volumio[1835]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Mar 09 05:41:02 sauls-volumio volumio[1835]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Mar 09 05:41:02 sauls-volumio volumio[1835]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Mar 09 05:41:02 sauls-volumio volumio[1835]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Mar 09 05:41:02 sauls-volumio volumio[1835]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) Mar 09 05:41:02 sauls-volumio volumio[1835]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Mar 09 05:41:02 sauls-volumio volumio[1835]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Mar 09 05:41:02 sauls-volumio volumio[1835]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Mar 09 05:41:02 sauls-volumio volumio[1835]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Mar 09 05:41:02 sauls-volumio volumio[1835]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Mar 09 05:41:02 sauls-volumio volumio[1835]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Mar 09 05:41:02 sauls-volumio volumio[1835]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 09 05:41:02 sauls-volumio volumio[1835]: info: MPD Permissions set Mar 09 05:41:02 sauls-volumio volumio[1835]: info: MPD Permissions set Mar 09 05:41:02 sauls-volumio volumio[1835]: info: VolumeController:: Volume=100 Mute =false Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreStateMachine::pushState Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CorePlayQueue::getTrack 0 Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::volumioPushState Mar 09 05:41:02 sauls-volumio volumio-remote-updater[574]: [2026-03-09 05:41:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1773034861 101 Mar 09 05:41:02 sauls-volumio volumio[1835]: 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 Mar 09 05:41:02 sauls-volumio volumio[1835]: info: Spotify config file written Mar 09 05:41:02 sauls-volumio sudo[2068]: volumio : unable to resolve host sauls-volumio Mar 09 05:41:02 sauls-volumio sudo[2068]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:41:02 sauls-volumio volumio[1835]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Mar 09 05:41:02 sauls-volumio sudo[2068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 09 05:41:02 sauls-volumio sudo[2068]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:02 sauls-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 05:41:02 sauls-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Mar 09 05:41:02 sauls-volumio systemd[1]: Stopped go-librespot Daemon. Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: No need to fix Spotify hosts Mar 09 05:41:02 sauls-volumio systemd[1]: Started go-librespot Daemon. Mar 09 05:41:02 sauls-volumio go-librespot[2074]: Librespot-go daemon starting... Mar 09 05:41:02 sauls-volumio volumio[1835]: info: VolumeController:: Volume=100 Mute =false Mar 09 05:41:02 sauls-volumio systemd[1]: Stopping go-librespot Daemon... Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreStateMachine::pushState Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CorePlayQueue::getTrack 0 Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::volumioPushState Mar 09 05:41:02 sauls-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Mar 09 05:41:02 sauls-volumio systemd[1]: go-librespot-daemon.service: Succeeded. Mar 09 05:41:02 sauls-volumio systemd[1]: Stopped go-librespot Daemon. Mar 09 05:41:02 sauls-volumio systemd[1]: Started go-librespot Daemon. Mar 09 05:41:02 sauls-volumio sudo[2068]: pam_unix(sudo:session): session closed for user root Mar 09 05:41:02 sauls-volumio volumio[1835]: info: [squeezelite_mc] Server discovery started Mar 09 05:41:02 sauls-volumio volumio[1835]: info: [squeezelite_mc] Player finder started Mar 09 05:41:02 sauls-volumio go-librespot[2080]: Librespot-go daemon starting... Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 05:41:02 sauls-volumio volumio[1835]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Mar 09 05:41:02 sauls-volumio go-librespot[2080]: time="2026-03-09T05:41:02Z" level=info msg="generated new device id: d2885322986a1a33323bc00b1fc0f64c2cd97f00" Mar 09 05:41:02 sauls-volumio go-librespot[2080]: time="2026-03-09T05:41:02Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 09 05:41:02 sauls-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 05:41:02 sauls-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 05:41:02 sauls-volumio volumio[1835]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 05:41:02 sauls-volumio volumio[1835]: Error: send ENETUNREACH 255.255.255.255:3483 Mar 09 05:41:02 sauls-volumio volumio[1835]: at doSend (dgram.js:692:16) Mar 09 05:41:02 sauls-volumio volumio[1835]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Mar 09 05:41:02 sauls-volumio volumio[1835]: at afterDns (dgram.js:638:5) Mar 09 05:41:02 sauls-volumio volumio[1835]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Mar 09 05:41:02 sauls-volumio volumio[1835]: errno: -101, Mar 09 05:41:02 sauls-volumio volumio[1835]: code: 'ENETUNREACH', Mar 09 05:41:02 sauls-volumio volumio[1835]: syscall: 'send', Mar 09 05:41:02 sauls-volumio volumio[1835]: address: '255.255.255.255', Mar 09 05:41:02 sauls-volumio volumio[1835]: port: 3483 Mar 09 05:41:02 sauls-volumio volumio[1835]: } Mar 09 05:41:02 sauls-volumio volumio[1835]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 05:41:02 sauls-volumio sudo[1901]: pam_unix(sudo:session): session closed for user root Mar 09 05:41:03 sauls-volumio dhcpcd[874]: wlan0: using IPv4LL address 169.254.248.52 Mar 09 05:41:03 sauls-volumio avahi-daemon[586]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.248.52. Mar 09 05:41:03 sauls-volumio avahi-daemon[586]: New relevant interface wlan0.IPv4 for mDNS. Mar 09 05:41:03 sauls-volumio avahi-daemon[586]: Registering new address record for 169.254.248.52 on wlan0.IPv4. Mar 09 05:41:03 sauls-volumio dhcpcd[874]: wlan0: adding route to 169.254.0.0/16 Mar 09 05:41:03 sauls-volumio dhcpcd[874]: wlan0: adding default route Mar 09 05:41:03 sauls-volumio mpd[2032]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 09 05:41:03 sauls-volumio mpd[2032]: output: No 'audio_output' defined in config file Mar 09 05:41:03 sauls-volumio mpd[2032]: output: Successfully detected a sndio audio device Mar 09 05:41:03 sauls-volumio mpd[2032]: zeroconf: No global port, disabling zeroconf Mar 09 05:41:03 sauls-volumio systemd[1]: Started Music Player Daemon. Mar 09 05:41:03 sauls-volumio sudo[1999]: pam_unix(sudo:session): session closed for user root Mar 09 05:41:03 sauls-volumio sudo[2011]: pam_unix(sudo:session): session closed for user root Mar 09 05:41:04 sauls-volumio ntpd[749]: Listen normally on 3 wlan0 169.254.248.52:123 Mar 09 05:41:04 sauls-volumio ntpd[749]: new interface(s) found: waking up resolver Mar 09 05:41:04 sauls-volumio volumio-time-update[580]: volumio-time-update-util: Fetching time from Volumio... Mar 09 05:41:06 sauls-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 09 05:41:06 sauls-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Mar 09 05:41:06 sauls-volumio systemd[1]: Stopped go-librespot Daemon. Mar 09 05:41:06 sauls-volumio systemd[1]: Started go-librespot Daemon. Mar 09 05:41:06 sauls-volumio go-librespot[2117]: Librespot-go daemon starting... Mar 09 05:41:06 sauls-volumio go-librespot[2117]: time="2026-03-09T05:41:06Z" level=info msg="generated new device id: bffd08095b89078105e0355d7da9ebdd10fc3778" Mar 09 05:41:06 sauls-volumio nmbd[699]: [2026/03/09 05:41:06.462917, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 09 05:41:06 sauls-volumio nmbd[699]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 09 05:41:06 sauls-volumio systemd[1]: Started Samba NMB Daemon. Mar 09 05:41:06 sauls-volumio systemd[1]: Starting Samba Winbind Daemon... Mar 09 05:41:06 sauls-volumio winbindd[2126]: [2026/03/09 05:41:06.695983, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 09 05:41:06 sauls-volumio winbindd[2126]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 09 05:41:06 sauls-volumio winbindd[2126]: [2026/03/09 05:41:06.715764, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 09 05:41:06 sauls-volumio winbindd[2126]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 09 05:41:06 sauls-volumio systemd[1]: Started Samba Winbind Daemon. Mar 09 05:41:06 sauls-volumio systemd[1]: Starting Samba SMB Daemon... Mar 09 05:41:07 sauls-volumio smbd[2131]: [2026/03/09 05:41:07.148660, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 09 05:41:07 sauls-volumio systemd[1]: Started Samba SMB Daemon. Mar 09 05:41:07 sauls-volumio smbd[2131]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 09 05:41:09 sauls-volumio dhcpcd[874]: wlan0: offered 192.168.0.229 from 192.168.0.1 Mar 09 05:41:09 sauls-volumio dhcpcd[874]: wlan0: probing address 192.168.0.229/24 Mar 09 05:41:13 sauls-volumio volumio5-onboarding[1941]: time=2026-03-09T05:41:13.637Z level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to get system info: could not get system info: context deadline exceeded" Mar 09 05:41:14 sauls-volumio dhcpcd[874]: wlan0: leased 192.168.0.229 for 3600 seconds Mar 09 05:41:14 sauls-volumio avahi-daemon[586]: Registering new address record for 192.168.0.229 on wlan0.IPv4. Mar 09 05:41:14 sauls-volumio dhcpcd[874]: wlan0: adding route to 192.168.0.0/24 Mar 09 05:41:14 sauls-volumio dhcpcd[874]: wlan0: changing default route via 192.168.0.1 Mar 09 05:41:15 sauls-volumio avahi-daemon[586]: Withdrawing address record for 169.254.248.52 on wlan0. Mar 09 05:41:15 sauls-volumio avahi-daemon[586]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.248.52. Mar 09 05:41:15 sauls-volumio avahi-daemon[586]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.229. Mar 09 05:41:15 sauls-volumio dhcpcd[874]: wlan0: deleting route to 169.254.0.0/16 Mar 09 05:41:15 sauls-volumio dhcpcd[874]: wlan0: pid 874 deleted default route via 192.168.0.1 Mar 09 05:41:16 sauls-volumio go-librespot[2117]: time="2026-03-09T05:41:16Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2026-03-09T05:41:16Z is before 2026-07-09T00:00:00Z" Mar 09 05:41:16 sauls-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 09 05:41:16 sauls-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 09 05:41:16 sauls-volumio ntpd[749]: Listen normally on 4 wlan0 192.168.0.229:123 Mar 09 05:41:16 sauls-volumio ntpd[749]: Deleting interface #3 wlan0, 169.254.248.52#123, interface stats: received=0, sent=0, dropped=0, active_time=12 secs Mar 09 05:41:16 sauls-volumio ntpd[749]: new interface(s) found: waking up resolver Mar 09 05:41:16 sauls-volumio ntpd[749]: Soliciting pool server 185.51.192.61 Mar 09 05:41:16 sauls-volumio ntpd[749]: Soliciting pool server 131.111.8.63 Mar 09 05:41:16 sauls-volumio ntpd[749]: Soliciting pool server 193.57.144.50 Mar 09 05:41:16 sauls-volumio ntpd[749]: Soliciting pool server 178.215.228.24 Mar 09 05:41:17 sauls-volumio ntpd[749]: Soliciting pool server 195.171.43.10 Mar 09 05:41:17 sauls-volumio ntpd[749]: Soliciting pool server 176.58.124.166 Mar 09 05:41:17 sauls-volumio ntpd[749]: Soliciting pool server 85.199.214.101 Mar 09 05:41:17 sauls-volumio ntpd[749]: Soliciting pool server 178.62.68.79 Mar 09 05:41:18 sauls-volumio sudo[2112]: volumio : unable to resolve host sauls-volumio Mar 09 05:41:18 sauls-volumio sudo[2112]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 09 05:41:18 sauls-volumio sudo[2112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-09 05:40 Mar 09 05:41:18 sauls-volumio sudo[2112]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"