-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2025-01-22 20:58:36 CET. -- Jan 22 20:57:00 volwo bthelper[815]: [CHG] Controller B8:27:EB:BA:B0:C0 Class: 0x0000041c Jan 22 20:57:00 volwo bthelper[815]: Changing power on succeeded Jan 22 20:57:00 volwo bthelper[815]: [CHG] Controller B8:27:EB:BA:B0:C0 Powered: yes Jan 22 20:57:00 volwo sudo[911]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:00 volwo wireless.js[706]: SETTING APPROPRIATE REG DOMAIN: DE Jan 22 20:57:00 volwo sudo[930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 22 20:57:00 volwo sudo[930]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:00 volwo sudo[930]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:00 volwo sudo[932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Jan 22 20:57:00 volwo sudo[932]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:00 volwo sudo[932]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:00 volwo wireless.js[706]: SUCCESSFULLY SET NEW REGDOMAIN: DE Jan 22 20:57:00 volwo wireless.js[706]: Start wireless flow Jan 22 20:57:00 volwo wireless.js[706]: Stopped hotspot (if there).. Jan 22 20:57:00 volwo sudo[937]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 22 20:57:00 volwo sudo[937]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:00 volwo sudo[937]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:00 volwo sudo[939]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 22 20:57:00 volwo sudo[939]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:00 volwo sudo[939]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:00 volwo wireless.js[706]: DHCP IP Jan 22 20:57:00 volwo wireless.js[706]: Start ap Jan 22 20:57:00 volwo wpa_supplicant[941]: Successfully initialized wpa_supplicant Jan 22 20:57:00 volwo sudo[942]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jan 22 20:57:00 volwo sudo[942]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:00 volwo kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 22 20:57:00 volwo dhcpcd[943]: dev: loaded udev Jan 22 20:57:00 volwo dhcpcd-run-hooks[961]: wlan0: starting wpa_supplicant Jan 22 20:57:01 volwo dhcpcd-run-hooks[966]: wlan0: failed to start wpa_supplicant Jan 22 20:57:01 volwo dhcpcd-run-hooks[967]: 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 Jan 22 20:57:01 volwo dhcpcd[943]: wlan0: connected to Access Point `' Jan 22 20:57:01 volwo dhcpcd[943]: no interfaces have a carrier Jan 22 20:57:01 volwo dhcpcd[943]: forked to background, child pid 977 Jan 22 20:57:01 volwo dhcpcd[977]: eth0: waiting for carrier Jan 22 20:57:01 volwo dhcpcd[977]: wlan0: waiting for carrier Jan 22 20:57:01 volwo sudo[942]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:01 volwo wireless.js[706]: trying... Jan 22 20:57:01 volwo sudo[979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 22 20:57:01 volwo sudo[979]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:01 volwo sudo[979]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:02 volwo wireless.js[706]: trying... Jan 22 20:57:02 volwo sudo[982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 22 20:57:02 volwo sudo[982]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:02 volwo sudo[982]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:03 volwo ifplugd(eth0)[862]: Link beat detected. Jan 22 20:57:03 volwo wireless.js[706]: trying... Jan 22 20:57:03 volwo sudo[985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 22 20:57:03 volwo sudo[985]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:03 volwo sudo[985]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:03 volwo ifplugd(eth0)[862]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jan 22 20:57:03 volwo ifplugd(eth0)[862]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jan 22 20:57:04 volwo sh[569]: eth0: carrier acquired Jan 22 20:57:04 volwo dhcpcd[977]: eth0: carrier acquired Jan 22 20:57:04 volwo dhcpcd[613]: eth0: carrier acquired Jan 22 20:57:04 volwo kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Up - 1Gbps/Full - flow control off Jan 22 20:57:04 volwo sh[569]: DUID 00:01:00:01:2e:f2:a2:d5:b8:27:eb:10:1a:6a Jan 22 20:57:04 volwo sh[569]: eth0: IAID eb:10:1a:6a Jan 22 20:57:04 volwo dhcpcd[977]: DUID 00:01:00:01:2e:f2:a2:d5:b8:27:eb:10:1a:6a Jan 22 20:57:04 volwo dhcpcd[613]: DUID 00:01:00:01:2e:f2:a2:d5:b8:27:eb:10:1a:6a Jan 22 20:57:04 volwo dhcpcd[977]: eth0: IAID eb:10:1a:6a Jan 22 20:57:04 volwo sh[569]: eth0: adding address fe80::6a7d:b6ed:6b2:7503 Jan 22 20:57:04 volwo sh[569]: ipv6_addaddr1: Permission denied Jan 22 20:57:04 volwo dhcpcd[613]: eth0: IAID eb:10:1a:6a Jan 22 20:57:04 volwo dhcpcd[977]: eth0: adding address fe80::6a7d:b6ed:6b2:7503 Jan 22 20:57:04 volwo dhcpcd[613]: eth0: adding address fe80::6a7d:b6ed:6b2:7503 Jan 22 20:57:04 volwo dhcpcd[977]: ipv6_addaddr1: Permission denied Jan 22 20:57:04 volwo dhcpcd[613]: ipv6_addaddr1: Permission denied Jan 22 20:57:04 volwo volumio-time-update[701]: volumio-time-update-util: Fetching time from Volumio... Jan 22 20:57:04 volwo volumio-time-update[701]: volumio-time-update-util: Date not found in response Jan 22 20:57:04 volwo volumio-time-update[701]: volumio-time-update-util: Retrying in 5 seconds... Jan 22 20:57:04 volwo sh[569]: eth0: soliciting an IPv6 router Jan 22 20:57:04 volwo dhcpcd[613]: eth0: soliciting an IPv6 router Jan 22 20:57:04 volwo wireless.js[706]: trying... Jan 22 20:57:04 volwo sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 22 20:57:04 volwo sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:04 volwo sudo[1002]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:05 volwo dhcpcd[977]: eth0: soliciting an IPv6 router Jan 22 20:57:05 volwo sh[569]: eth0: rebinding lease of 192.168.2.33 Jan 22 20:57:05 volwo dhcpcd[613]: eth0: rebinding lease of 192.168.2.33 Jan 22 20:57:05 volwo dhcpcd[977]: eth0: rebinding lease of 192.168.2.33 Jan 22 20:57:05 volwo wireless.js[706]: trying... Jan 22 20:57:05 volwo sudo[1005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 22 20:57:05 volwo sudo[1005]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:05 volwo sudo[1005]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:06 volwo volumio-remote-updater[699]: [2025-01-22 20:57:06] [info] asio async_connect error: system:111 (Connection refused) Jan 22 20:57:06 volwo volumio-remote-updater[699]: [2025-01-22 20:57:06] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 22 20:57:06 volwo volumio-remote-updater[699]: [2025-01-22 20:57:06] [error] handle_connect error: Underlying Transport Error Jan 22 20:57:06 volwo wireless.js[706]: trying... Jan 22 20:57:06 volwo sudo[1008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 22 20:57:06 volwo sudo[1008]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:06 volwo sudo[1008]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:07 volwo wireless.js[706]: trying... Jan 22 20:57:07 volwo sudo[1011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 22 20:57:07 volwo sudo[1011]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:07 volwo sudo[1011]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:08 volwo wireless.js[706]: trying... Jan 22 20:57:08 volwo sudo[1014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 22 20:57:08 volwo sudo[1014]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:08 volwo sudo[1014]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:09 volwo volumio-time-update[701]: volumio-time-update-util: Fetching time from Volumio... Jan 22 20:57:09 volwo volumio-time-update[701]: volumio-time-update-util: Date not found in response Jan 22 20:57:09 volwo volumio-time-update[701]: volumio-time-update-util: Retrying in 5 seconds... Jan 22 20:57:09 volwo wireless.js[706]: trying... Jan 22 20:57:09 volwo sudo[1025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 22 20:57:09 volwo sudo[1025]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:09 volwo sudo[1025]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:10 volwo sh[569]: eth0: probing for an IPv4LL address Jan 22 20:57:10 volwo dhcpcd[613]: eth0: probing for an IPv4LL address Jan 22 20:57:10 volwo sh[569]: eth0: DHCP lease expired Jan 22 20:57:10 volwo dhcpcd[613]: eth0: DHCP lease expired Jan 22 20:57:10 volwo sh[569]: eth0: soliciting a DHCP lease Jan 22 20:57:10 volwo dhcpcd[613]: eth0: soliciting a DHCP lease Jan 22 20:57:10 volwo dhcpcd[977]: eth0: probing for an IPv4LL address Jan 22 20:57:10 volwo dhcpcd[977]: eth0: DHCP lease expired Jan 22 20:57:10 volwo dhcpcd[977]: eth0: soliciting a DHCP lease Jan 22 20:57:10 volwo wireless.js[706]: trying... Jan 22 20:57:10 volwo sudo[1047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 22 20:57:10 volwo sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:10 volwo sudo[1047]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:11 volwo wireless.js[706]: trying... Jan 22 20:57:11 volwo sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 22 20:57:11 volwo sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:11 volwo sudo[1050]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:12 volwo wireless.js[706]: trying... Jan 22 20:57:12 volwo sudo[1053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 22 20:57:12 volwo sudo[1053]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:12 volwo sudo[1053]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:13 volwo wireless.js[706]: trying... Jan 22 20:57:13 volwo sudo[1056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 22 20:57:13 volwo sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:13 volwo sudo[1056]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:14 volwo volumio-time-update[701]: volumio-time-update-util: Fetching time from Volumio... Jan 22 20:57:14 volwo volumio-time-update[701]: volumio-time-update-util: Date not found in response Jan 22 20:57:14 volwo volumio-time-update[701]: volumio-time-update-util: Retrying in 5 seconds... Jan 22 20:57:14 volwo wireless.js[706]: trying... Jan 22 20:57:14 volwo sudo[1064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 22 20:57:14 volwo sudo[1064]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:14 volwo sudo[1064]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:15 volwo dhcpcd[977]: eth0: using IPv4LL address 169.254.155.21 Jan 22 20:57:15 volwo avahi-daemon[717]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.155.21. Jan 22 20:57:15 volwo avahi-daemon[717]: New relevant interface eth0.IPv4 for mDNS. Jan 22 20:57:15 volwo dhcpcd[977]: eth0: adding route to 169.254.0.0/16 Jan 22 20:57:15 volwo dhcpcd[977]: eth0: adding default route Jan 22 20:57:15 volwo avahi-daemon[717]: Registering new address record for 169.254.155.21 on eth0.IPv4. Jan 22 20:57:15 volwo sh[569]: eth0: using IPv4LL address 169.254.155.21 Jan 22 20:57:15 volwo sh[569]: eth0: adding route to 169.254.0.0/16 Jan 22 20:57:15 volwo sh[569]: eth0: adding default route Jan 22 20:57:15 volwo dhcpcd[613]: eth0: using IPv4LL address 169.254.155.21 Jan 22 20:57:15 volwo dhcpcd[613]: eth0: adding route to 169.254.0.0/16 Jan 22 20:57:15 volwo dhcpcd[613]: eth0: adding default route Jan 22 20:57:15 volwo sh[569]: forked to background, child pid 1089 Jan 22 20:57:15 volwo dhcpcd[613]: forked to background, child pid 1089 Jan 22 20:57:15 volwo wireless.js[706]: trying... Jan 22 20:57:15 volwo sudo[1109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 22 20:57:15 volwo sudo[1109]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:15 volwo sudo[1109]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:15 volwo ifplugd(eth0)[862]: client: ifup: interface eth0 already configured Jan 22 20:57:15 volwo sh[569]: eth0=eth0 Jan 22 20:57:15 volwo ifplugd(eth0)[862]: Program executed successfully. Jan 22 20:57:16 volwo nmbd[821]: [2025/01/22 20:57:16.256200, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 22 20:57:16 volwo nmbd[821]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 22 20:57:16 volwo systemd[1]: Started Samba NMB Daemon. Jan 22 20:57:16 volwo systemd[1]: Starting Samba Winbind Daemon... Jan 22 20:57:16 volwo winbindd[1118]: [2025/01/22 20:57:16.594712, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 22 20:57:16 volwo winbindd[1118]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 22 20:57:16 volwo winbindd[1118]: [2025/01/22 20:57:16.615879, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 22 20:57:16 volwo winbindd[1118]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 22 20:57:16 volwo systemd[1]: Started Samba Winbind Daemon. Jan 22 20:57:16 volwo systemd[1]: Starting Samba SMB Daemon... Jan 22 20:57:16 volwo volumio-remote-updater[699]: [2025-01-22 20:57:16] [info] asio async_connect error: system:111 (Connection refused) Jan 22 20:57:16 volwo volumio-remote-updater[699]: [2025-01-22 20:57:16] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 22 20:57:16 volwo volumio-remote-updater[699]: [2025-01-22 20:57:16] [error] handle_connect error: Underlying Transport Error Jan 22 20:57:16 volwo wireless.js[706]: trying... Jan 22 20:57:16 volwo sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 22 20:57:16 volwo sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:16 volwo sudo[1125]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:17 volwo ntpd[870]: Listen normally on 3 eth0 169.254.155.21:123 Jan 22 20:57:17 volwo ntpd[870]: new interface(s) found: waking up resolver Jan 22 20:57:17 volwo smbd[1123]: [2025/01/22 20:57:17.155633, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 22 20:57:17 volwo systemd[1]: Started Samba SMB Daemon. Jan 22 20:57:17 volwo smbd[1123]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 22 20:57:17 volwo wireless.js[706]: trying... Jan 22 20:57:17 volwo sudo[1135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 22 20:57:17 volwo sudo[1135]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:17 volwo sudo[1135]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:18 volwo wireless.js[706]: trying... Jan 22 20:57:18 volwo sudo[1138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 22 20:57:18 volwo sudo[1138]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:18 volwo sudo[1138]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:19 volwo volumio-time-update[701]: volumio-time-update-util: Fetching time from Volumio... Jan 22 20:57:19 volwo wireless.js[706]: trying... Jan 22 20:57:19 volwo sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 22 20:57:19 volwo sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:19 volwo sudo[1148]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:20 volwo wireless.js[706]: trying... Jan 22 20:57:20 volwo sudo[1151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 22 20:57:20 volwo sudo[1151]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:20 volwo sudo[1151]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:21 volwo wireless.js[706]: Overtime, starting plan B Jan 22 20:57:21 volwo wireless.js[706]: STARTING HOTSPOT Jan 22 20:57:21 volwo wpa_supplicant[965]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Jan 22 20:57:21 volwo wpa_supplicant[965]: nl80211: Failed to set IPv4 unicast in multicast filter Jan 22 20:57:21 volwo wpa_supplicant[965]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Jan 22 20:57:21 volwo wpa_supplicant[965]: nl80211: Failed to set IPv4 unicast in multicast filter Jan 22 20:57:21 volwo wpa_supplicant[965]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 Jan 22 20:57:21 volwo wpa_supplicant[965]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING Jan 22 20:57:21 volwo systemd[1]: systemd-fsckd.service: Succeeded. Jan 22 20:57:22 volwo wpa_supplicant[965]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 22 20:57:22 volwo wpa_supplicant[965]: wlan0: CTRL-EVENT-TERMINATING Jan 22 20:57:24 volwo sudo[1160]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 22 20:57:24 volwo sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:24 volwo sudo[1160]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:24 volwo sudo[1162]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 22 20:57:24 volwo sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:24 volwo sudo[1162]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:24 volwo avahi-daemon[717]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jan 22 20:57:24 volwo avahi-daemon[717]: New relevant interface wlan0.IPv4 for mDNS. Jan 22 20:57:24 volwo kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 22 20:57:24 volwo avahi-daemon[717]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jan 22 20:57:25 volwo systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jan 22 20:57:25 volwo systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 22 20:57:25 volwo dnsmasq[1169]: dnsmasq: syntax check OK. Jan 22 20:57:25 volwo hostapd[1168]: Configuration file: /etc/hostapd/hostapd.conf Jan 22 20:57:25 volwo hostapd[1168]: wlan0: Could not connect to kernel driver Jan 22 20:57:25 volwo hostapd[1168]: Using interface wlan0 with hwaddr b8:27:eb:45:4f:3f and ssid "Volumio-881C7" Jan 22 20:57:25 volwo dnsmasq[1175]: started, version 2.80 cachesize 150 Jan 22 20:57:25 volwo dnsmasq[1175]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jan 22 20:57:25 volwo dnsmasq-dhcp[1175]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jan 22 20:57:25 volwo dnsmasq[1175]: reading /etc/resolv.conf Jan 22 20:57:25 volwo dnsmasq[1175]: using nameserver 208.67.222.222#53 Jan 22 20:57:25 volwo dnsmasq[1175]: using nameserver 208.67.220.220#53 Jan 22 20:57:25 volwo dnsmasq[1175]: read /etc/hosts - 1 addresses Jan 22 20:57:25 volwo systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jan 22 20:57:25 volwo systemd[1]: Reached target Host and Network Name Lookups. Jan 22 20:57:25 volwo dhcpcd[977]: wlan0: carrier acquired Jan 22 20:57:25 volwo hostapd[1168]: wlan0: interface state UNINITIALIZED->ENABLED Jan 22 20:57:25 volwo hostapd[1168]: wlan0: AP-ENABLED Jan 22 20:57:25 volwo systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jan 22 20:57:25 volwo dhcpcd[977]: wlan0: IAID eb:45:4f:3f Jan 22 20:57:25 volwo dhcpcd[977]: wlan0: adding address fe80::a7b2:cb61:ae57:6852 Jan 22 20:57:25 volwo dhcpcd[977]: ipv6_addaddr1: Permission denied Jan 22 20:57:25 volwo iwconfig[1184]: Error for wireless request "Set Power Management" (8B2C) : Jan 22 20:57:25 volwo iwconfig[1184]: SET failed on device wlan0 ; Invalid argument. Jan 22 20:57:25 volwo systemd[1]: Started Wireless Services. Jan 22 20:57:25 volwo systemd[1]: Started Volumio Backend Module. Jan 22 20:57:25 volwo systemd[1]: Started Volumio Cpu Tweaker. Jan 22 20:57:25 volwo volumio-cpu-tweak[1186]: Setting RT Priority for mpd Jan 22 20:57:25 volwo volumio-cpu-tweak[1186]: Setting MPD Affinity Jan 22 20:57:25 volwo volumio-cpu-tweak[1186]: pid 880's current affinity mask: f Jan 22 20:57:25 volwo volumio-cpu-tweak[1186]: pid 880's new affinity mask: 3 Jan 22 20:57:25 volwo volumio-cpu-tweak[1186]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jan 22 20:57:25 volwo volumio-cpu-tweak[1186]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jan 22 20:57:25 volwo systemd[1]: volumio_cpu_tweak.service: Succeeded. Jan 22 20:57:25 volwo systemd[1]: systemd-hostnamed.service: Succeeded. Jan 22 20:57:25 volwo dhcpcd[977]: wlan0: soliciting an IPv6 router Jan 22 20:57:26 volwo dhcpcd[977]: wlan0: soliciting a DHCP lease Jan 22 20:57:26 volwo volumio-remote-updater[699]: [2025-01-22 20:57:26] [info] asio async_connect error: system:111 (Connection refused) Jan 22 20:57:26 volwo volumio-remote-updater[699]: [2025-01-22 20:57:26] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 22 20:57:26 volwo volumio-remote-updater[699]: [2025-01-22 20:57:26] [error] handle_connect error: Underlying Transport Error Jan 22 20:57:27 volwo ntpd[870]: Listen normally on 4 wlan0 192.168.211.1:123 Jan 22 20:57:27 volwo ntpd[870]: new interface(s) found: waking up resolver Jan 22 20:57:28 volwo volumio[1185]: info: ------------------------------------------- Jan 22 20:57:28 volwo volumio[1185]: info: ----- Volumio3 ---- Jan 22 20:57:28 volwo volumio[1185]: info: ------------------------------------------- Jan 22 20:57:28 volwo volumio[1185]: info: ----- System startup ---- Jan 22 20:57:28 volwo volumio[1185]: info: ------------------------------------------- Jan 22 20:57:29 volwo volumio[1185]: info: MYVOLUMIO Environment detected Jan 22 20:57:29 volwo volumio[1185]: info: Plugin folders cleanup Jan 22 20:57:29 volwo volumio[1185]: info: Scanning into folder /volumio/app/plugins/ Jan 22 20:57:29 volwo volumio[1185]: info: Scanning category audio_interface Jan 22 20:57:29 volwo volumio[1185]: info: Scanning category miscellanea Jan 22 20:57:29 volwo volumio[1185]: info: Scanning category music_service Jan 22 20:57:29 volwo volumio[1185]: info: Scanning category plugins.json Jan 22 20:57:29 volwo volumio[1185]: info: Scanning category system_controller Jan 22 20:57:29 volwo volumio[1185]: info: Scanning category user_interface Jan 22 20:57:29 volwo volumio[1185]: info: Scanning into folder /data/plugins/ Jan 22 20:57:29 volwo volumio[1185]: info: Scanning category user_interface Jan 22 20:57:29 volwo volumio[1185]: info: Plugin folders cleanup completed Jan 22 20:57:29 volwo volumio[1185]: info: ------------------------------------------- Jan 22 20:57:29 volwo volumio[1185]: info: ----- Core plugins startup ---- Jan 22 20:57:29 volwo volumio[1185]: info: ------------------------------------------- Jan 22 20:57:29 volwo volumio[1185]: info: Loading plugins from folder /volumio/app/plugins/ Jan 22 20:57:29 volwo volumio[1185]: info: Adding plugin upnp to MyMusic Plugins Jan 22 20:57:29 volwo volumio[1185]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 22 20:57:29 volwo volumio[1185]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 22 20:57:29 volwo volumio[1185]: info: Loading plugins from folder /data/plugins/ Jan 22 20:57:29 volwo volumio[1185]: info: Loading plugin "system"... Jan 22 20:57:29 volwo volumio[1185]: info: Loading plugin "appearance"... Jan 22 20:57:30 volwo volumio[1185]: info: Loading plugin "network"... Jan 22 20:57:30 volwo volumio[1185]: info: Refreshing Cached IP Addresses Jan 22 20:57:30 volwo sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 22 20:57:30 volwo sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:30 volwo sudo[1223]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:30 volwo sudo[1225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 22 20:57:30 volwo sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:30 volwo volumio[1185]: info: Loading plugin "services"... Jan 22 20:57:30 volwo sudo[1225]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:30 volwo volumio[1185]: info: Loading plugin "alsa_controller"... Jan 22 20:57:30 volwo sudo[1234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 22 20:57:30 volwo sudo[1234]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:30 volwo sudo[1234]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:30 volwo kernel: ieee80211 phy0: brcmf_vif_set_mgmt_ie: vndr ie set error : -52 Jan 22 20:57:30 volwo kernel: ieee80211 phy0: brcmf_cfg80211_scan: scan error (-52) Jan 22 20:57:30 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 22 20:57:30 volwo volumio[1185]: info: Loading plugin "wizard"... Jan 22 20:57:30 volwo volumio[1185]: info: Loading plugin "networkfs"... Jan 22 20:57:31 volwo volumio[1185]: info: Starting Udev Watcher for removable devices Jan 22 20:57:31 volwo sudo[1251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.98/Audio /mnt/NAS/Audio Jan 22 20:57:31 volwo sudo[1251]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:31 volwo volumio[1185]: info: Ignoring mount for partition: boot Jan 22 20:57:31 volwo volumio[1185]: info: Ignoring mount for partition: volumio Jan 22 20:57:31 volwo volumio[1185]: info: Ignoring mount for partition: volumio_data Jan 22 20:57:31 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 22 20:57:31 volwo volumio[1185]: info: Loading plugin "volumio_command_line_client"... Jan 22 20:57:31 volwo volumio[1185]: info: Loading plugin "upnp"... Jan 22 20:57:31 volwo volumio[1185]: info: [1737575851156] Starting Upmpd Daemon Jan 22 20:57:31 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 22 20:57:31 volwo volumio[1185]: info: Loading plugin "my_music"... Jan 22 20:57:31 volwo volumio[1185]: info: Loading plugin "mpd"... Jan 22 20:57:31 volwo dhcpcd[977]: wlan0: probing for an IPv4LL address Jan 22 20:57:31 volwo kernel: Key type cifs.spnego registered Jan 22 20:57:31 volwo kernel: Key type cifs.idmap registered Jan 22 20:57:31 volwo 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. Jan 22 20:57:31 volwo kernel: CIFS: Attempting to mount //192.168.2.98/Audio Jan 22 20:57:31 volwo volumio[1185]: info: Loading plugin "upnp_browser"... Jan 22 20:57:32 volwo volumio[1185]: info: Loading plugin "alarm-clock"... Jan 22 20:57:32 volwo volumio[1185]: info: Loading plugin "airplay_emulation"... Jan 22 20:57:32 volwo volumio[1185]: info: Starting Shairport Sync Jan 22 20:57:32 volwo volumio[1185]: info: Loading plugin "last_100"... Jan 22 20:57:32 volwo volumio[1185]: info: Loading plugin "webradio"... Jan 22 20:57:32 volwo volumio[1185]: info: Loading plugin "i2s_dacs"... Jan 22 20:57:32 volwo volumio[1185]: info: Loading plugin "volumiodiscovery"... Jan 22 20:57:32 volwo volumio[1185]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 22 20:57:32 volwo volumio[1185]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 22 20:57:32 volwo volumio[1185]: *** WARNING *** For more information see Jan 22 20:57:32 volwo volumio[1185]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 22 20:57:32 volwo volumio[1185]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 22 20:57:32 volwo node[1185]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 22 20:57:32 volwo volumio[1185]: *** WARNING *** For more information see Jan 22 20:57:32 volwo node[1185]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 22 20:57:32 volwo node[1185]: *** WARNING *** For more information see Jan 22 20:57:32 volwo node[1185]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 22 20:57:32 volwo node[1185]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 22 20:57:32 volwo node[1185]: *** WARNING *** For more information see Jan 22 20:57:32 volwo volumio[1185]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 22 20:57:32 volwo volumio[1185]: info: Discovery: Started advertising with name: volwo Jan 22 20:57:32 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 22 20:57:32 volwo volumio[1185]: info: Loading plugin "outputs"... Jan 22 20:57:32 volwo volumio[1185]: info: Loading plugin "albumart"... Jan 22 20:57:32 volwo volumio[1185]: info: Plugin example_plugin is not enabled Jan 22 20:57:32 volwo volumio[1185]: info: Loading plugin "inputs"... Jan 22 20:57:32 volwo volumio[1185]: info: Loading plugin "updater_comm"... Jan 22 20:57:32 volwo volumio[1185]: info: Plugin mpdemulation is not enabled Jan 22 20:57:32 volwo volumio[1185]: info: Loading plugin "rest_api"... Jan 22 20:57:32 volwo volumio[1185]: info: Loading plugin "websocket"... Jan 22 20:57:32 volwo volumio[1185]: info: Starting Socket.io Server version 2.3.0 Jan 22 20:57:32 volwo volumio[1185]: info: Loading plugin "touch_display"... Jan 22 20:57:32 volwo volumio[1185]: Forking 3 albumart workers Jan 22 20:57:33 volwo volumio[1185]: info: Applying required configuration parameters for plugin touch_display Jan 22 20:57:33 volwo volumio[1185]: info: Loading i18n strings for locale de Jan 22 20:57:33 volwo volumio[1185]: Updating browse sources language Jan 22 20:57:33 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 22 20:57:33 volwo volumio[1185]: Starting albumart workers Jan 22 20:57:33 volwo volumio[1185]: Starting albumart workers Jan 22 20:57:33 volwo volumio[1185]: Starting albumart workers Jan 22 20:57:34 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 22 20:57:34 volwo volumio[1185]: info: CoreCommandRouter::initPlayerControls Jan 22 20:57:34 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:34 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:34 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:34 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:34 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:34 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:34 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:34 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:34 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 22 20:57:34 volwo volumio[1185]: Express server listening on port 3000 Jan 22 20:57:34 volwo volumio[1185]: [Metrics] WebUI: 7s 43.14ms Jan 22 20:57:34 volwo volumio[1185]: info: CoreStateMachine::resetVolumioState Jan 22 20:57:34 volwo volumio[1185]: info: CoreStateMachine::getcurrentVolume Jan 22 20:57:34 volwo volumio[1185]: info: CoreCommandRouter::volumioRetrievevolume Jan 22 20:57:34 volwo volumio[1185]: info: Volumio Network Manager: Network status updated: 1 Jan 22 20:57:34 volwo volumio[1185]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 22 20:57:34 volwo volumio[1185]: wlan0 Interface doesn't support scanning : Invalid exchange Jan 22 20:57:34 volwo volumio[1185]: info: Cannot use regular scanning, forcing with ap-force Jan 22 20:57:34 volwo sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 22 20:57:34 volwo sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:36 volwo dhcpcd[977]: wlan0: using IPv4LL address 169.254.225.81 Jan 22 20:57:36 volwo avahi-daemon[717]: Registering new address record for 169.254.225.81 on wlan0.IPv4. Jan 22 20:57:36 volwo dhcpcd[977]: wlan0: adding route to 169.254.0.0/16 Jan 22 20:57:36 volwo volumio-remote-updater[699]: [2025-01-22 20:57:36] [connect] Successful connection Jan 22 20:57:37 volwo avahi-daemon[717]: Withdrawing address record for 192.168.211.1 on wlan0. Jan 22 20:57:37 volwo avahi-daemon[717]: Withdrawing address record for 169.254.155.21 on eth0. Jan 22 20:57:37 volwo avahi-daemon[717]: Host name conflict, retrying with volwo-2 Jan 22 20:57:37 volwo avahi-daemon[717]: Registering new address record for 169.254.225.81 on wlan0.IPv4. Jan 22 20:57:37 volwo avahi-daemon[717]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jan 22 20:57:37 volwo avahi-daemon[717]: Registering new address record for 169.254.155.21 on eth0.IPv4. Jan 22 20:57:37 volwo sudo[1251]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:37 volwo kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 22 20:57:37 volwo kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 22 20:57:38 volwo sudo[1301]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:38 volwo volumio[1185]: info: Cannot mount NAS Audio at system boot, trial number 1 ,retrying in 5 seconds Jan 22 20:57:38 volwo volumio[1185]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jan 22 20:57:38 volwo volumio-remote-updater[699]: [2025-01-22 20:57:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1737575856 101 Jan 22 20:57:38 volwo volumio[1185]: 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 Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 22 20:57:38 volwo volumio[1185]: info: Reloading queue from file Jan 22 20:57:38 volwo volumio[1185]: info: VolumeController:: Volume=100 Mute =false Jan 22 20:57:38 volwo volumio[1185]: info: CoreStateMachine::pushState Jan 22 20:57:38 volwo volumio[1185]: info: CorePlayQueue::getTrack 0 Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::volumioPushState Jan 22 20:57:38 volwo volumio[1185]: info: CoreStateMachine::updateTrackBlock Jan 22 20:57:38 volwo volumio[1185]: info: CorePlayQueue::getTrackBlock Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::volumioRetrievevolume Jan 22 20:57:38 volwo volumio[1185]: info: Setting Device type: Raspberry PI Jan 22 20:57:38 volwo volumio[1185]: info: CoreStateMachine::setRepeat null single undefined Jan 22 20:57:38 volwo volumio[1185]: info: CoreStateMachine::pushState Jan 22 20:57:38 volwo volumio[1185]: info: CorePlayQueue::getTrack 0 Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::volumioPushState Jan 22 20:57:38 volwo volumio[1185]: info: CoreStateMachine::setRandom null Jan 22 20:57:38 volwo volumio[1185]: info: CoreStateMachine::pushState Jan 22 20:57:38 volwo volumio[1185]: info: CorePlayQueue::getTrack 0 Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::volumioPushState Jan 22 20:57:38 volwo volumio[1185]: info: Completed loading Core Plugins Jan 22 20:57:38 volwo volumio[1185]: info: Preparing to generate the ALSA configuration file Jan 22 20:57:38 volwo avahi-daemon[717]: Server startup complete. Host name is volwo-2.local. Local service cookie is 2286989397. Jan 22 20:57:38 volwo volumio[1185]: info: Asound.conf file unchanged, so no further update is needed Jan 22 20:57:38 volwo volumio[1185]: info: Output device has changed, restarting MPD Jan 22 20:57:38 volwo volumio[1185]: info: Output device has changed, restarting Shairport Sync Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 22 20:57:38 volwo sudo[1349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 22 20:57:38 volwo sudo[1349]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:38 volwo sudo[1349]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:38 volwo sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 22 20:57:38 volwo sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:38 volwo volumio[1185]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 22 20:57:38 volwo volumio[1185]: info: ___________ START PLUGINS ___________ Jan 22 20:57:38 volwo systemd[1]: Stopping Music Player Daemon... Jan 22 20:57:38 volwo volumio[1185]: info: ControllerMpd::onStart: Initializing MPD Jan 22 20:57:38 volwo volumio[1185]: info: Creating MPD Configuration file Jan 22 20:57:38 volwo dhcpcd[977]: eth0: offered 192.168.2.33 from 192.168.2.1 Jan 22 20:57:38 volwo dhcpcd[977]: eth0: probing address 192.168.2.33/24 Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 22 20:57:38 volwo volumio[1185]: info: [1737575858535] CoreMusicLibrary::Adding element Medienserver Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 22 20:57:38 volwo sudo[1358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 22 20:57:38 volwo sudo[1358]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:38 volwo sudo[1358]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:38 volwo sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 22 20:57:38 volwo sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 22 20:57:38 volwo volumio[1185]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 22 20:57:38 volwo dhcpcd[1089]: eth0: offered 192.168.2.33 from 192.168.2.1 Jan 22 20:57:38 volwo dhcpcd[1089]: eth0: probing address 192.168.2.33/24 Jan 22 20:57:38 volwo systemd[1]: mpd.service: Succeeded. Jan 22 20:57:38 volwo systemd[1]: Stopped Music Player Daemon. Jan 22 20:57:38 volwo volumio[1185]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 22 20:57:38 volwo volumio[1185]: info: [1737575858662] CoreMusicLibrary::Adding element Last_100 Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 22 20:57:38 volwo volumio[1185]: info: [1737575858671] CoreMusicLibrary::Adding element Webradio Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 22 20:57:38 volwo volumio[1185]: info: Initializing BBC Radios Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 22 20:57:38 volwo volumio[1185]: info: Loading i18n strings for locale de Jan 22 20:57:38 volwo systemd[1]: Starting Music Player Daemon... Jan 22 20:57:38 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 22 20:57:38 volwo volumio[1185]: info: Volumio Calling Home Jan 22 20:57:38 volwo sudo[1369]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 22 20:57:38 volwo sudo[1369]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:38 volwo sudo[1369]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:38 volwo sudo[1384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 22 20:57:38 volwo sudo[1384]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:38 volwo sudo[1387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 22 20:57:38 volwo sudo[1387]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:38 volwo sudo[1391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 22 20:57:38 volwo sudo[1391]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:38 volwo sudo[1384]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:38 volwo systemd[1]: Reloading. Jan 22 20:57:39 volwo ntpd[870]: Listen normally on 5 wlan0 169.254.225.81:123 Jan 22 20:57:39 volwo volumio[1185]: info: VolumeController:: Volume=100 Mute =false Jan 22 20:57:39 volwo volumio[1185]: info: CoreStateMachine::pushState Jan 22 20:57:39 volwo ntpd[870]: new interface(s) found: waking up resolver Jan 22 20:57:39 volwo volumio[1185]: info: CorePlayQueue::getTrack 0 Jan 22 20:57:39 volwo volumio[1185]: info: CoreCommandRouter::volumioPushState Jan 22 20:57:39 volwo volumio[1185]: info: touch_display: Backlight interface detected. Jan 22 20:57:39 volwo volumio[1185]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 22 20:57:39 volwo volumio[1185]: info: MPD Permissions set Jan 22 20:57:39 volwo volumio[1185]: info: MPD Permissions set Jan 22 20:57:39 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:39 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:39 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:39 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:39 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:39 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:39 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:39 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 22 20:57:39 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:39 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:39 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:39 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:39 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:39 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:39 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:39 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:39 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:39 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 22 20:57:39 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:57:39 volwo volumio[1185]: info: touch_display: Raspberry Pi Foundation touch screen detected. Jan 22 20:57:39 volwo sudo[1418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jan 22 20:57:39 volwo sudo[1418]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:39 volwo volumio[1185]: info: Starting Shairport Sync Jan 22 20:57:39 volwo sudo[1420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jan 22 20:57:39 volwo sudo[1420]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:39 volwo sudo[1418]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:39 volwo sudo[1420]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:39 volwo sudo[1423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness Jan 22 20:57:39 volwo sudo[1423]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:39 volwo avahi-daemon[717]: Service "volwo" (/services/volumio.service) successfully established. Jan 22 20:57:39 volwo volumio[1185]: info: Starting Shairport Sync Jan 22 20:57:39 volwo sudo[1423]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:39 volwo systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 22 20:57:39 volwo volumio[1185]: info: Starting Shairport Sync Jan 22 20:57:39 volwo sudo[1427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 22 20:57:39 volwo sudo[1427]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:39 volwo sudo[1429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 22 20:57:39 volwo sudo[1429]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:39 volwo volumio[1185]: info: touch_display: File permissions for backlight brightness control set. Jan 22 20:57:39 volwo volumio[1185]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jan 22 20:57:39 volwo volumio[1185]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jan 22 20:57:39 volwo sudo[1433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 22 20:57:39 volwo volumio[1185]: info: Discovery: A device disappeared from network Jan 22 20:57:39 volwo volumio[1185]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Jan 22 20:57:39 volwo sudo[1433]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:39 volwo volumio[1185]: info: Discovery: A device disappeared from network Jan 22 20:57:39 volwo volumio[1185]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Jan 22 20:57:39 volwo volumio[1185]: info: Discovery: A device disappeared from network Jan 22 20:57:39 volwo volumio[1185]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Jan 22 20:57:39 volwo volumio-time-update[701]: volumio-time-update-util: Date not found in response Jan 22 20:57:39 volwo volumio-time-update[701]: volumio-time-update-util: Retrying in 5 seconds... Jan 22 20:57:39 volwo sudo[1387]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:39 volwo volumio[1185]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jan 22 20:57:39 volwo systemd[1]: Reloading. Jan 22 20:57:40 volwo systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 22 20:57:40 volwo mpd[1388]: Jan 22 20:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 22 20:57:40 volwo sudo[1391]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:40 volwo volumio[1185]: info: touch_display: systemctl daemon-reload succeeded. Jan 22 20:57:40 volwo systemd[1]: Started Music Player Daemon. Jan 22 20:57:40 volwo sudo[1360]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:40 volwo sudo[1351]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:40 volwo sudo[1456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jan 22 20:57:40 volwo sudo[1456]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:40 volwo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 22 20:57:40 volwo systemd[1]: shairport-sync.service: Succeeded. Jan 22 20:57:40 volwo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 22 20:57:40 volwo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 22 20:57:40 volwo sudo[1433]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:40 volwo sudo[1427]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:40 volwo sudo[1429]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:40 volwo systemd[1]: Started Volumio Kiosk. Jan 22 20:57:40 volwo sudo[1456]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:40 volwo volumio[1185]: error: MPD error: The expression evaluated to a falsy value: Jan 22 20:57:40 volwo volumio[1185]: assert.ok(self.idling) Jan 22 20:57:40 volwo volumio[1185]: error: The expression evaluated to a falsy value: Jan 22 20:57:40 volwo volumio[1185]: assert.ok(self.idling) Jan 22 20:57:40 volwo volumio[1185]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jan 22 20:57:40 volwo volumio[1185]: info: touch_display: Volumio Kiosk started. Jan 22 20:57:40 volwo volumio[1185]: info: CoreCommandRouter::volumioGetState Jan 22 20:57:40 volwo volumio[1185]: info: CorePlayQueue::getTrack 0 Jan 22 20:57:40 volwo volumio[1185]: info: Completed starting Core Plugins Jan 22 20:57:40 volwo volumio[1185]: info: ------------------------------------------- Jan 22 20:57:40 volwo volumio[1185]: info: ----- MyVolumio plugins startup ---- Jan 22 20:57:40 volwo volumio[1185]: info: ------------------------------------------- Jan 22 20:57:40 volwo volumio[1185]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 22 20:57:40 volwo volumio[1185]: info: Shairport-Sync Started Jan 22 20:57:40 volwo volumio[1185]: Error adding Membership: Error: addMembership EINVAL Jan 22 20:57:40 volwo volumio[1185]: info: Shairport-Sync Started Jan 22 20:57:40 volwo volumio[1185]: info: MPD running with PID1388 Jan 22 20:57:40 volwo volumio[1185]: ,establishing connection Jan 22 20:57:40 volwo volumio[1185]: error: updateQueue error: null Jan 22 20:57:40 volwo volumio[1185]: error: updateQueue error: null Jan 22 20:57:40 volwo volumio[1185]: info: touch_display: X display number found: Jan 22 20:57:41 volwo volumio[1185]: info: touch_display: X display number found: 0 Jan 22 20:57:41 volwo startx[1464]: X.Org X Server 1.20.4 Jan 22 20:57:41 volwo startx[1464]: X Protocol Version 11, Revision 0 Jan 22 20:57:41 volwo startx[1464]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jan 22 20:57:41 volwo startx[1464]: Current Operating System: Linux volwo 6.6.62-v7+ #1816 SMP Mon Nov 18 15:30:49 GMT 2024 armv7l Jan 22 20:57:41 volwo startx[1464]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_hdmi=0 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=fb80cb44-b4d3-4b36-be35-613cba0a09fb imgfile=/volumio_current.sqsh bootpart=UUID=51B1-040D datapart=UUID=2899bccc-8620-4c7a-b56a-43fd2f7a836a uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Jan 22 20:57:41 volwo startx[1464]: Build Date: 04 April 2023 07:50:56AM Jan 22 20:57:41 volwo startx[1464]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jan 22 20:57:41 volwo startx[1464]: Current version of pixman: 0.36.0 Jan 22 20:57:41 volwo startx[1464]: Before reporting problems, check http://wiki.x.org Jan 22 20:57:41 volwo startx[1464]: to make sure that you have the latest version. Jan 22 20:57:41 volwo startx[1464]: Markers: (--) probed, (**) from config file, (==) default setting, Jan 22 20:57:41 volwo startx[1464]: (++) from command line, (!!) notice, (II) informational, Jan 22 20:57:41 volwo startx[1464]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jan 22 20:57:41 volwo startx[1464]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Jan 22 20:57:41 2025 Jan 22 20:57:41 volwo startx[1464]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jan 22 20:57:41 volwo startx[1464]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jan 22 20:57:41 volwo volumio[1185]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 22 20:57:41 volwo volumio[1185]: info: touch_display: X display number found: 0 Jan 22 20:57:41 volwo sudo[1512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 22 20:57:41 volwo sudo[1512]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:41 volwo sudo[1512]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:41 volwo sudo[1514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 22 20:57:41 volwo sudo[1514]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:41 volwo sudo[1514]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:41 volwo sudo[1518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 22 20:57:41 volwo sudo[1518]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:41 volwo systemd[1]: Started UPnP Renderer front-end to MPD. Jan 22 20:57:41 volwo sudo[1518]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:41 volwo volumio[1185]: info: Upmpdcli Daemon Started Jan 22 20:57:41 volwo volumio[1520]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 22 20:57:42 volwo kernel: broken atomic modeset userspace detected, disabling atomic Jan 22 20:57:42 volwo startx[1464]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Jan 22 20:57:43 volwo sudo[1539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.98/Audio /mnt/NAS/Audio Jan 22 20:57:43 volwo sudo[1539]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:43 volwo kernel: CIFS: Attempting to mount //192.168.2.98/Audio Jan 22 20:57:43 volwo dhcpcd[1089]: eth0: leased 192.168.2.33 for 864000 seconds Jan 22 20:57:43 volwo avahi-daemon[717]: Registering new address record for 192.168.2.33 on eth0.IPv4. Jan 22 20:57:43 volwo dhcpcd[1089]: eth0: adding route to 192.168.2.0/24 Jan 22 20:57:43 volwo dhcpcd[1089]: eth0: changing default route via 192.168.2.1 Jan 22 20:57:43 volwo dhcpcd[977]: eth0: pid 1089 deleted default route Jan 22 20:57:43 volwo dnsmasq[1175]: reading /etc/resolv.conf Jan 22 20:57:43 volwo dnsmasq[1175]: using nameserver 192.168.2.1#53 Jan 22 20:57:43 volwo dnsmasq[1175]: using nameserver 208.67.222.222#53 Jan 22 20:57:43 volwo dnsmasq[1175]: using nameserver 208.67.220.220#53 Jan 22 20:57:43 volwo avahi-daemon[717]: Withdrawing address record for 169.254.155.21 on eth0. Jan 22 20:57:43 volwo avahi-daemon[717]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.155.21. Jan 22 20:57:43 volwo avahi-daemon[717]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.2.33. Jan 22 20:57:43 volwo dhcpcd[977]: eth0: pid 1089 deleted IP address 169.254.155.21/16 Jan 22 20:57:43 volwo dhcpcd[977]: wlan0: adding default route Jan 22 20:57:43 volwo dhcpcd[977]: eth0: deleting route to 169.254.0.0/16 Jan 22 20:57:43 volwo dhcpcd[1089]: eth0: deleting route to 169.254.0.0/16 Jan 22 20:57:43 volwo dhcpcd[1089]: eth0: pid 1089 deleted default route via 192.168.2.1 Jan 22 20:57:43 volwo volumio[1185]: info: CoreCommandRouter::volumioGetState Jan 22 20:57:43 volwo volumio[1185]: info: CorePlayQueue::getTrack 0 Jan 22 20:57:43 volwo dhcpcd[977]: eth0: probing for an IPv4LL address Jan 22 20:57:43 volwo volumio[1185]: info: touch_display: Setting screensaver timeout to 20 seconds. Jan 22 20:57:43 volwo dhcpcd[977]: eth0: leased 192.168.2.33 for 864000 seconds Jan 22 20:57:43 volwo dhcpcd[977]: eth0: adding route to 192.168.2.0/24 Jan 22 20:57:43 volwo dhcpcd[977]: eth0: adding default route via 192.168.2.1 Jan 22 20:57:43 volwo dhcpcd[977]: wlan0: deleting default route Jan 22 20:57:44 volwo volumio-time-update[701]: volumio-time-update-util: Fetching time from Volumio... Jan 22 20:57:45 volwo ntpd[870]: Listen normally on 6 eth0 192.168.2.33:123 Jan 22 20:57:45 volwo ntpd[870]: Deleting interface #3 eth0, 169.254.155.21#123, interface stats: received=0, sent=0, dropped=0, active_time=28 secs Jan 22 20:57:45 volwo ntpd[870]: new interface(s) found: waking up resolver Jan 22 20:57:46 volwo volumio[1185]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 22 20:57:51 volwo sudo[1539]: pam_unix(sudo:session): session closed for user root Jan 22 20:57:51 volwo kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 22 20:57:51 volwo kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 22 20:57:51 volwo volumio[1185]: info: Cannot mount NAS Audio at system boot, trial number 2 ,retrying in 5 seconds Jan 22 20:57:56 volwo sudo[1848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.98/Audio /mnt/NAS/Audio Jan 22 20:57:56 volwo sudo[1848]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:57:56 volwo kernel: CIFS: Attempting to mount //192.168.2.98/Audio Jan 22 20:57:57 volwo volumio[1185]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jan 22 20:57:58 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 22 20:57:58 volwo volumio[1185]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jan 22 20:57:59 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 22 20:57:59 volwo volumio[1185]: info: CoreCommandRouter::volumioGetVisibleSources Jan 22 20:57:59 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 22 20:57:59 volwo volumio[1185]: info: CoreCommandRouter::volumioGetState Jan 22 20:57:59 volwo volumio[1185]: info: CorePlayQueue::getTrack 0 Jan 22 20:57:59 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 22 20:57:59 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 22 20:57:59 volwo volumio[1185]: info: Received Get System Info Jan 22 20:57:59 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 22 20:57:59 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 22 20:57:59 volwo volumio[1185]: info: Discovery: Getting this device information Jan 22 20:57:59 volwo volumio[1185]: info: CoreCommandRouter::volumioGetState Jan 22 20:57:59 volwo volumio[1185]: info: CorePlayQueue::getTrack 0 Jan 22 20:57:59 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 22 20:57:59 volwo volumio[1185]: info: CoreCommandRouter::volumioGetState Jan 22 20:57:59 volwo volumio[1185]: info: CorePlayQueue::getTrack 0 Jan 22 20:57:59 volwo volumio[1185]: info: Listing playlists Jan 22 20:58:00 volwo volumio[1185]: error: MyVolumio Plugin failed to start in a timely fashion Jan 22 20:58:00 volwo volumio[1185]: [Metrics] CommandRouter: 32s 884.31ms Jan 22 20:58:00 volwo volumio[1185]: info: CoreCommandRouter::volumiosetStartupVolume Jan 22 20:58:00 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 22 20:58:00 volwo volumio[1185]: info: VolumeController:: Setting startup Volume 75 Jan 22 20:58:00 volwo volumio[1185]: info: VolumeController::SetAlsaVolume75 Jan 22 20:58:00 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 22 20:58:00 volwo volumio[1185]: info: CoreCommandRouter::Close All Modals sent Jan 22 20:58:00 volwo volumio[1185]: info: CoreCommandRouter::Close All Modals sent Jan 22 20:58:00 volwo volumio[1185]: info: CoreStateMachine::pushState Jan 22 20:58:00 volwo volumio[1185]: info: CorePlayQueue::getTrack 0 Jan 22 20:58:00 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 22 20:58:00 volwo volumio[1185]: info: CoreCommandRouter::volumioPushState Jan 22 20:58:01 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 22 20:58:01 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 22 20:58:01 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 22 20:58:01 volwo volumio[1185]: info: Received Get System Info Jan 22 20:58:01 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 22 20:58:01 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 22 20:58:01 volwo volumio[1185]: info: Discovery: Getting this device information Jan 22 20:58:01 volwo volumio[1185]: info: CoreCommandRouter::volumioGetState Jan 22 20:58:01 volwo volumio[1185]: info: CorePlayQueue::getTrack 0 Jan 22 20:58:01 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 22 20:58:01 volwo volumio[1520]: ...........................................................................................................................................................................++++ Jan 22 20:58:01 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 22 20:58:01 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 22 20:58:01 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 22 20:58:02 volwo sudo[1848]: pam_unix(sudo:session): session closed for user root Jan 22 20:58:02 volwo volumio[1185]: info: Cannot mount NAS Audio at system boot, trial number 3 ,retrying in 5 seconds Jan 22 20:58:02 volwo kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 22 20:58:02 volwo kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 22 20:58:03 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 22 20:58:03 volwo volumio[1185]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.785&uuid=881c754629ba29663d6994d4be033e8b" http://updates.volumio.org/downloader-v1/track-device Jan 22 20:58:03 volwo volumio[1185]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 22 20:58:03 volwo volumio[1185]: Dload Upload Total Spent Left Speed Jan 22 20:58:03 volwo volumio[1185]: [1.2K blob data] Jan 22 20:58:03 volwo volumio[1185]: retrying in 5 seconds, trial 0 Jan 22 20:58:03 volwo volumio[1185]: info: Volumio Calling Home Jan 22 20:58:07 volwo sudo[1891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.98/Audio /mnt/NAS/Audio Jan 22 20:58:07 volwo sudo[1891]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:58:07 volwo kernel: CIFS: Attempting to mount //192.168.2.98/Audio Jan 22 20:58:07 volwo volumio[1185]: info: BOOT COMPLETED Jan 22 20:58:08 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 22 20:58:08 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 22 20:58:08 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 22 20:58:08 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 22 20:58:12 volwo volumio-time-update[701]: volumio-time-update-util: Date not found in response Jan 22 20:58:12 volwo volumio-time-update[701]: volumio-time-update-util: Retrying in 5 seconds... Jan 22 20:58:13 volwo sudo[1891]: pam_unix(sudo:session): session closed for user root Jan 22 20:58:13 volwo volumio[1185]: info: Cannot mount NAS Audio at system boot, trial number 4 ,retrying in 5 seconds Jan 22 20:58:13 volwo kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 22 20:58:13 volwo kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 22 20:58:16 volwo volumio[1520]: .................................................................................................................................++++ Jan 22 20:58:16 volwo volumio[1520]: e is 65537 (0x010001) Jan 22 20:58:16 volwo volumio[1520]: writing RSA key Jan 22 20:58:17 volwo volumio-time-update[701]: volumio-time-update-util: Fetching time from Volumio... Jan 22 20:58:19 volwo sudo[1932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.98/Audio /mnt/NAS/Audio Jan 22 20:58:19 volwo sudo[1932]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 22 20:58:19 volwo kernel: CIFS: Attempting to mount //192.168.2.98/Audio Jan 22 20:58:21 volwo volumio[1185]: info: Discovery: adding a634c283-2660-449f-8ece-12e07c707170 Jan 22 20:58:21 volwo volumio[1185]: info: Discovery: Found device volk Jan 22 20:58:21 volwo volumio[1185]: info: Discovery: Connecting to remote: 192.168.2.31 Jan 22 20:58:21 volwo volumio[1185]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 22 20:58:21 volwo volumio[1185]: info: Discovery: Connected to remote: 192.168.2.31 Jan 22 20:58:21 volwo volumio[1185]: info: Discovery: adding cb0c757b-7ec4-4c0f-9c41-34fc1e116828 Jan 22 20:58:21 volwo volumio[1185]: info: Discovery: Found device volwo Jan 22 20:58:21 volwo volumio[1185]: info: CoreCommandRouter::volumioGetState Jan 22 20:58:21 volwo volumio[1185]: info: CorePlayQueue::getTrack 0 Jan 22 20:58:25 volwo sudo[1932]: pam_unix(sudo:session): session closed for user root Jan 22 20:58:25 volwo volumio[1185]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jan 22 20:58:25 volwo kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 22 20:58:25 volwo kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 22 20:58:31 volwo ntpd[870]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 22 20:58:32 volwo nmbd[821]: [2025/01/22 20:58:32.328597, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jan 22 20:58:32 volwo nmbd[821]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Jan 22 20:58:34 volwo volumio[1185]: verbose: New Socket.io Connection to 192.168.2.33:3000 from 192.168.2.31 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 22 20:58:34 volwo volumio[1185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 22 20:58:34 volwo volumio[1185]: info: CoreCommandRouter::volumioGetState Jan 22 20:58:34 volwo volumio[1185]: info: CorePlayQueue::getTrack 0 Jan 22 20:58:35 volwo volumio[1185]: info: Discovery: this is already registered, cb0c757b-7ec4-4c0f-9c41-34fc1e116828 Jan 22 20:58:35 volwo volumio[1185]: info: Discovery: Found device volwo Jan 22 20:58:35 volwo volumio[1185]: info: CoreCommandRouter::volumioGetState Jan 22 20:58:35 volwo volumio[1185]: info: CorePlayQueue::getTrack 0 Jan 22 20:58:35 volwo volumio[1185]: info: Discovery: this is already registered, cb0c757b-7ec4-4c0f-9c41-34fc1e116828 Jan 22 20:58:35 volwo volumio[1185]: info: Discovery: Found device volwo Jan 22 20:58:35 volwo volumio[1185]: info: CoreCommandRouter::volumioGetState Jan 22 20:58:35 volwo volumio[1185]: info: CorePlayQueue::getTrack 0 Jan 22 20:58:35 volwo volumio[1185]: info: Discovery: this is already registered, a634c283-2660-449f-8ece-12e07c707170 Jan 22 20:58:35 volwo volumio[1185]: info: Discovery: Found device volk Jan 22 20:58:35 volwo volumio[1185]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 22 20:58:35 volwo volumio[1185]: info: Discovery: this is already registered, cb0c757b-7ec4-4c0f-9c41-34fc1e116828 Jan 22 20:58:35 volwo volumio[1185]: info: Discovery: Found device volwo Jan 22 20:58:35 volwo volumio[1185]: info: CoreCommandRouter::volumioGetState Jan 22 20:58:35 volwo volumio[1185]: info: CorePlayQueue::getTrack 0 Jan 22 20:58:35 volwo volumio[1185]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 22 20:58:35 volwo volumio[1185]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 22 20:58:35 volwo volumio[1185]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 22 20:58:35 volwo volumio[1185]: info: CoreCommandRouter::volumioGetState Jan 22 20:58:35 volwo volumio[1185]: info: CorePlayQueue::getTrack 0 Jan 22 20:58:36 volwo volumio[1185]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 22 20:58:36 volwo volumio[1185]: Error: certificate is not yet valid Jan 22 20:58:36 volwo volumio[1185]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jan 22 20:58:36 volwo volumio[1185]: at TLSSocket.emit (events.js:315:20) Jan 22 20:58:36 volwo volumio[1185]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jan 22 20:58:36 volwo volumio[1185]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jan 22 20:58:36 volwo volumio[1185]: code: 'CERT_NOT_YET_VALID' Jan 22 20:58:36 volwo volumio[1185]: } Jan 22 20:58:36 volwo volumio[1185]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 22 20:58:36 volwo ntpd[870]: Soliciting pool server 178.63.52.31 Jan 22 20:58:36 volwo ntpd[870]: Soliciting pool server 193.203.3.171 Jan 22 20:58:36 volwo ntpd[870]: Soliciting pool server 162.159.200.1 Jan 22 20:58:36 volwo sudo[1982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-22 20:57 Jan 22 20:58:36 volwo sudo[1982]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"