-- Logs begin at Wed 2024-10-16 17:10:26 AEST, end at Wed 2024-10-16 17:12:25 AEST. -- Oct 16 17:11:23 tz1 ntpd[825]: receive: Unexpected origin timestamp 0xeab9e576.30d5c7e2 does not match aorg 0000000000.00000000 from server@13.55.50.68 xmt 0xeab9e59b.297ce655 Oct 16 17:11:23 tz1 ntpd[825]: receive: Unexpected origin timestamp 0xeab9e576.30e95d18 does not match aorg 0000000000.00000000 from server@170.64.249.120 xmt 0xeab9e59b.29c63228 Oct 16 17:11:23 tz1 ntpd[825]: receive: Unexpected origin timestamp 0xeab9e576.30e520af does not match aorg 0000000000.00000000 from server@129.250.35.250 xmt 0xeab9e59b.29a0ffb5 Oct 16 17:11:23 tz1 ntpd[825]: receive: Unexpected origin timestamp 0xeab9e576.30eeca8f does not match aorg 0000000000.00000000 from server@180.150.8.191 xmt 0xeab9e59b.29bfd1fd Oct 16 17:11:23 tz1 sudo[999]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:23 tz1 wireless.js[648]: DHCP IP Oct 16 17:11:23 tz1 wireless.js[648]: Start ap Oct 16 17:11:23 tz1 wpa_supplicant[1014]: Successfully initialized wpa_supplicant Oct 16 17:11:23 tz1 sudo[1015]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Oct 16 17:11:23 tz1 sudo[1015]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:23 tz1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 16 17:11:23 tz1 dhcpcd[1016]: dev: loaded udev Oct 16 17:11:23 tz1 dhcpcd[1016]: wlan0: connected to Access Point `' Oct 16 17:11:23 tz1 dhcpcd[1016]: eth1: waiting for carrier Oct 16 17:11:23 tz1 dhcpcd[1016]: DUID 00:01:00:01:2e:7c:57:96:a0:ce:c8:ff:14:f6 Oct 16 17:11:23 tz1 dhcpcd[1016]: eth0: IAID c8:ff:14:f6 Oct 16 17:11:23 tz1 dhcpcd[1016]: eth0: adding address fe80::5f6c:7ce4:80a7:6787 Oct 16 17:11:23 tz1 dhcpcd[1016]: ipv6_addaddr1: Permission denied Oct 16 17:11:23 tz1 dhcpcd[1016]: wlan0: waiting for carrier Oct 16 17:11:24 tz1 wpa_supplicant[1045]: wlan0: Trying to associate with SSID 'AID-Local' Oct 16 17:11:24 tz1 wireless.js[648]: trying... Oct 16 17:11:24 tz1 dhcpcd[1016]: eth0: rebinding lease of 172.24.116.82 Oct 16 17:11:24 tz1 sudo[1064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 17:11:24 tz1 sudo[1064]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:24 tz1 sudo[1064]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:24 tz1 dhcpcd[1016]: eth0: soliciting an IPv6 router Oct 16 17:11:24 tz1 dhcpcd[1016]: eth0: leased 172.24.116.82 for 86400 seconds Oct 16 17:11:24 tz1 dhcpcd[1016]: eth0: adding route to 172.24.0.0/17 Oct 16 17:11:24 tz1 dhcpcd[1016]: eth0: adding default route via 172.24.0.1 Oct 16 17:11:24 tz1 dhcpcd[1016]: forked to background, child pid 1087 Oct 16 17:11:24 tz1 sudo[1015]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:24 tz1 wpa_supplicant[1045]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 16 17:11:25 tz1 wireless.js[648]: trying... Oct 16 17:11:25 tz1 sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 17:11:25 tz1 sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:25 tz1 sudo[1089]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:25 tz1 wpa_supplicant[1045]: wlan0: Trying to associate with SSID 'AID-Local' Oct 16 17:11:26 tz1 volumio-remote-updater[649]: [2024-10-16 17:11:26] [info] asio async_connect error: system:111 (Connection refused) Oct 16 17:11:26 tz1 volumio-remote-updater[649]: [2024-10-16 17:11:26] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 16 17:11:26 tz1 volumio-remote-updater[649]: [2024-10-16 17:11:26] [error] handle_connect error: Underlying Transport Error Oct 16 17:11:26 tz1 wireless.js[648]: trying... Oct 16 17:11:26 tz1 sudo[1092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 17:11:26 tz1 sudo[1092]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:26 tz1 sudo[1092]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:26 tz1 wpa_supplicant[1045]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 16 17:11:27 tz1 wireless.js[648]: trying... Oct 16 17:11:27 tz1 sudo[1095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 17:11:27 tz1 sudo[1095]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:27 tz1 sudo[1095]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:27 tz1 wpa_supplicant[1045]: wlan0: Trying to associate with SSID 'AID-Local' Oct 16 17:11:28 tz1 wireless.js[648]: trying... Oct 16 17:11:28 tz1 sudo[1098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 17:11:28 tz1 sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:28 tz1 sudo[1098]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:28 tz1 wpa_supplicant[1045]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 16 17:11:29 tz1 wireless.js[648]: trying... Oct 16 17:11:29 tz1 wpa_supplicant[1045]: wlan0: Trying to associate with SSID 'AID-Local' Oct 16 17:11:29 tz1 sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 17:11:29 tz1 sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:29 tz1 sudo[1101]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:30 tz1 wpa_supplicant[1045]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 16 17:11:30 tz1 wireless.js[648]: trying... Oct 16 17:11:30 tz1 sudo[1104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 17:11:30 tz1 sudo[1104]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:30 tz1 sudo[1104]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:31 tz1 wireless.js[648]: trying... Oct 16 17:11:31 tz1 wpa_supplicant[1045]: wlan0: Trying to associate with SSID 'wifi1.aid.net' Oct 16 17:11:31 tz1 sudo[1107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 17:11:31 tz1 sudo[1107]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:31 tz1 sudo[1107]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:32 tz1 wpa_supplicant[1045]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 16 17:11:32 tz1 wireless.js[648]: trying... Oct 16 17:11:32 tz1 sudo[1110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 17:11:32 tz1 sudo[1110]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:32 tz1 sudo[1110]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:32 tz1 wpa_supplicant[1045]: wlan0: Trying to associate with SSID 'wifi1.aid.net' Oct 16 17:11:33 tz1 wireless.js[648]: trying... Oct 16 17:11:33 tz1 sudo[1113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 17:11:33 tz1 sudo[1113]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:33 tz1 sudo[1113]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:33 tz1 wpa_supplicant[1045]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 16 17:11:34 tz1 wireless.js[648]: trying... Oct 16 17:11:34 tz1 sudo[1116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 17:11:34 tz1 sudo[1116]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:34 tz1 sudo[1116]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:34 tz1 wpa_supplicant[1045]: wlan0: Trying to associate with SSID 'wifi1.aid.net' Oct 16 17:11:35 tz1 wpa_supplicant[1045]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 16 17:11:35 tz1 wireless.js[648]: trying... Oct 16 17:11:35 tz1 sudo[1119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 17:11:35 tz1 sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:35 tz1 sudo[1119]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:35 tz1 wpa_supplicant[1045]: wlan0: Trying to associate with SSID 'wifi1.aid.net' Oct 16 17:11:36 tz1 volumio-remote-updater[649]: [2024-10-16 17:11:36] [info] asio async_connect error: system:111 (Connection refused) Oct 16 17:11:36 tz1 volumio-remote-updater[649]: [2024-10-16 17:11:36] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 16 17:11:36 tz1 volumio-remote-updater[649]: [2024-10-16 17:11:36] [error] handle_connect error: Underlying Transport Error Oct 16 17:11:36 tz1 wireless.js[648]: trying... Oct 16 17:11:36 tz1 sudo[1122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 17:11:36 tz1 sudo[1122]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:36 tz1 sudo[1122]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:36 tz1 wpa_supplicant[1045]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 16 17:11:36 tz1 systemd[1]: systemd-fsckd.service: Succeeded. Oct 16 17:11:37 tz1 wireless.js[648]: trying... Oct 16 17:11:37 tz1 sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 17:11:37 tz1 sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:37 tz1 sudo[1125]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:37 tz1 wpa_supplicant[1045]: wlan0: Trying to associate with SSID 'AID-Local' Oct 16 17:11:38 tz1 wireless.js[648]: trying... Oct 16 17:11:38 tz1 sudo[1128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 17:11:38 tz1 sudo[1128]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:38 tz1 sudo[1128]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:38 tz1 wpa_supplicant[1045]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 16 17:11:39 tz1 wireless.js[648]: trying... Oct 16 17:11:39 tz1 sudo[1131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 17:11:39 tz1 sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:39 tz1 sudo[1131]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:40 tz1 wpa_supplicant[1045]: wlan0: Trying to associate with SSID 'AID-Local' Oct 16 17:11:40 tz1 wireless.js[648]: trying... Oct 16 17:11:40 tz1 sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 17:11:40 tz1 sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:40 tz1 sudo[1134]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:40 tz1 wpa_supplicant[1045]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 16 17:11:40 tz1 wpa_supplicant[1045]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="AID-Local" auth_failures=1 duration=10 reason=CONN_FAILED Oct 16 17:11:41 tz1 wireless.js[648]: trying... Oct 16 17:11:41 tz1 sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 17:11:41 tz1 sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:41 tz1 sudo[1137]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:42 tz1 wireless.js[648]: trying... Oct 16 17:11:42 tz1 sudo[1140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 17:11:42 tz1 sudo[1140]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:42 tz1 sudo[1140]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:43 tz1 wireless.js[648]: trying... Oct 16 17:11:43 tz1 sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 17:11:43 tz1 sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:43 tz1 sudo[1143]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:44 tz1 wireless.js[648]: Overtime, starting plan B Oct 16 17:11:44 tz1 wireless.js[648]: STARTING HOTSPOT Oct 16 17:11:44 tz1 wpa_supplicant[1045]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Oct 16 17:11:44 tz1 wpa_supplicant[1045]: nl80211: Failed to set IPv4 unicast in multicast filter Oct 16 17:11:44 tz1 wpa_supplicant[1045]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Oct 16 17:11:44 tz1 wpa_supplicant[1045]: nl80211: Failed to set IPv4 unicast in multicast filter Oct 16 17:11:44 tz1 wpa_supplicant[1045]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 Oct 16 17:11:44 tz1 wpa_supplicant[1045]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING Oct 16 17:11:44 tz1 wpa_supplicant[1045]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 16 17:11:45 tz1 wpa_supplicant[1045]: wlan0: CTRL-EVENT-TERMINATING Oct 16 17:11:45 tz1 systemd[1]: systemd-hostnamed.service: Succeeded. Oct 16 17:11:46 tz1 volumio-remote-updater[649]: [2024-10-16 17:11:46] [info] asio async_connect error: system:111 (Connection refused) Oct 16 17:11:46 tz1 volumio-remote-updater[649]: [2024-10-16 17:11:46] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 16 17:11:46 tz1 volumio-remote-updater[649]: [2024-10-16 17:11:46] [error] handle_connect error: Underlying Transport Error Oct 16 17:11:47 tz1 sudo[1154]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 16 17:11:47 tz1 sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:47 tz1 sudo[1154]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:47 tz1 sudo[1156]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 16 17:11:47 tz1 sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:47 tz1 sudo[1156]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:47 tz1 wireless.js[648]: Hotspot is disabled, not starting it Oct 16 17:11:47 tz1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 16 17:11:47 tz1 systemd[1]: Started Wireless Services. Oct 16 17:11:47 tz1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 16 17:11:47 tz1 systemd[1]: Started Volumio Backend Module. Oct 16 17:11:47 tz1 systemd[1]: Started Volumio Cpu Tweaker. Oct 16 17:11:47 tz1 systemd[1]: Reached target Multi-User System. Oct 16 17:11:47 tz1 systemd[1]: Reached target Graphical Interface. Oct 16 17:11:47 tz1 systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 16 17:11:47 tz1 volumio-cpu-tweak[1163]: Setting RT Priority for mpd Oct 16 17:11:47 tz1 volumio-cpu-tweak[1163]: pid 35's current scheduling policy: SCHED_OTHER Oct 16 17:11:47 tz1 volumio-cpu-tweak[1163]: pid 35's current scheduling priority: 0 Oct 16 17:11:47 tz1 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 16 17:11:47 tz1 volumio-cpu-tweak[1163]: Setting MPD Affinity Oct 16 17:11:47 tz1 systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 16 17:11:47 tz1 systemd[1]: Startup finished in 23.811s (kernel) + 47.140s (userspace) = 1min 10.951s. Oct 16 17:11:47 tz1 volumio-cpu-tweak[1163]: pid 3's current affinity mask: f Oct 16 17:11:47 tz1 volumio-cpu-tweak[1163]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 16 17:11:47 tz1 volumio-cpu-tweak[1163]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 16 17:11:48 tz1 systemd[1]: volumio_cpu_tweak.service: Succeeded. Oct 16 17:11:53 tz1 volumio[1162]: info: ------------------------------------------- Oct 16 17:11:53 tz1 volumio[1162]: info: ----- Volumio3 ---- Oct 16 17:11:53 tz1 volumio[1162]: info: ------------------------------------------- Oct 16 17:11:53 tz1 volumio[1162]: info: ----- System startup ---- Oct 16 17:11:53 tz1 volumio[1162]: info: ------------------------------------------- Oct 16 17:11:55 tz1 volumio[1162]: info: MYVOLUMIO Environment detected Oct 16 17:11:55 tz1 volumio[1162]: info: Plugin folders cleanup Oct 16 17:11:55 tz1 volumio[1162]: info: Scanning into folder /volumio/app/plugins/ Oct 16 17:11:55 tz1 volumio[1162]: info: Scanning category audio_interface Oct 16 17:11:55 tz1 volumio[1162]: info: Scanning category miscellanea Oct 16 17:11:55 tz1 volumio[1162]: info: Scanning category music_service Oct 16 17:11:55 tz1 volumio[1162]: info: Scanning category plugins.json Oct 16 17:11:55 tz1 volumio[1162]: info: Scanning category system_controller Oct 16 17:11:55 tz1 volumio[1162]: info: Scanning category user_interface Oct 16 17:11:55 tz1 volumio[1162]: info: Scanning into folder /data/plugins/ Oct 16 17:11:55 tz1 volumio[1162]: info: Scanning category music_service Oct 16 17:11:55 tz1 volumio[1162]: info: Plugin folders cleanup completed Oct 16 17:11:55 tz1 volumio[1162]: info: ------------------------------------------- Oct 16 17:11:55 tz1 volumio[1162]: info: ----- Core plugins startup ---- Oct 16 17:11:55 tz1 volumio[1162]: info: ------------------------------------------- Oct 16 17:11:55 tz1 volumio[1162]: info: Loading plugins from folder /volumio/app/plugins/ Oct 16 17:11:55 tz1 volumio[1162]: info: Adding plugin upnp to MyMusic Plugins Oct 16 17:11:55 tz1 volumio[1162]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 16 17:11:55 tz1 volumio[1162]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 16 17:11:55 tz1 volumio[1162]: info: Loading plugins from folder /data/plugins/ Oct 16 17:11:55 tz1 volumio[1162]: info: Loading plugin "system"... Oct 16 17:11:55 tz1 volumio[1162]: info: Loading plugin "appearance"... Oct 16 17:11:56 tz1 volumio-remote-updater[649]: [2024-10-16 17:11:56] [connect] Successful connection Oct 16 17:11:58 tz1 volumio[1162]: info: Loading plugin "network"... Oct 16 17:11:58 tz1 volumio[1162]: info: Refreshing Cached IP Addresses Oct 16 17:11:58 tz1 sudo[1194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 17:11:58 tz1 sudo[1194]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:58 tz1 sudo[1194]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:58 tz1 volumio[1162]: info: Loading plugin "services"... Oct 16 17:11:58 tz1 sudo[1196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 17:11:58 tz1 sudo[1196]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:58 tz1 sudo[1196]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:58 tz1 volumio[1162]: info: Loading plugin "alsa_controller"... Oct 16 17:11:58 tz1 sudo[1199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 16 17:11:58 tz1 sudo[1199]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:59 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 17:11:59 tz1 volumio[1162]: info: Loading plugin "wizard"... Oct 16 17:11:59 tz1 volumio[1162]: info: Loading plugin "networkfs"... Oct 16 17:11:59 tz1 volumio[1162]: info: Starting Udev Watcher for removable devices Oct 16 17:11:59 tz1 sudo[1207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=jacob,password=Legend14,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.160/Media/music /mnt/NAS/tower Oct 16 17:11:59 tz1 sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:59 tz1 sudo[1209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.0.160:Media/music /mnt/NAS/TOWER Oct 16 17:11:59 tz1 sudo[1209]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:11:59 tz1 volumio[1162]: info: Ignoring mount for partition: boot Oct 16 17:11:59 tz1 volumio[1162]: info: Ignoring mount for partition: volumio Oct 16 17:11:59 tz1 volumio[1162]: info: Ignoring mount for partition: volumio_data Oct 16 17:11:59 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 17:11:59 tz1 volumio[1162]: info: Loading plugin "volumio_command_line_client"... Oct 16 17:11:59 tz1 volumio[1162]: info: Loading plugin "upnp"... Oct 16 17:11:59 tz1 volumio[1162]: info: [1729062719482] Starting Upmpd Daemon Oct 16 17:11:59 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 17:11:59 tz1 volumio[1162]: info: Loading plugin "my_music"... Oct 16 17:11:59 tz1 volumio[1162]: info: Loading plugin "mpd"... Oct 16 17:11:59 tz1 sudo[1199]: pam_unix(sudo:session): session closed for user root Oct 16 17:11:59 tz1 kernel: Key type cifs.spnego registered Oct 16 17:11:59 tz1 kernel: Key type cifs.idmap registered Oct 16 17:11:59 tz1 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. Oct 16 17:11:59 tz1 kernel: CIFS: Attempting to mount \\192.168.0.160\Media Oct 16 17:12:00 tz1 volumio[1162]: info: Loading plugin "upnp_browser"... Oct 16 17:12:01 tz1 volumio[1162]: info: Loading plugin "alarm-clock"... Oct 16 17:12:01 tz1 volumio[1162]: info: Loading plugin "airplay_emulation"... Oct 16 17:12:01 tz1 volumio[1162]: info: Starting Shairport Sync Oct 16 17:12:01 tz1 volumio[1162]: info: Loading plugin "last_100"... Oct 16 17:12:01 tz1 volumio[1162]: info: Loading plugin "webradio"... Oct 16 17:12:02 tz1 volumio[1162]: info: Loading plugin "i2s_dacs"... Oct 16 17:12:02 tz1 volumio[1162]: info: I2S DAC not set, start Auto-detection Oct 16 17:12:02 tz1 volumio[1162]: info: Loading plugin "volumiodiscovery"... Oct 16 17:12:02 tz1 volumio[1162]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 16 17:12:02 tz1 node[1162]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 16 17:12:02 tz1 volumio[1162]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 17:12:02 tz1 node[1162]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 17:12:02 tz1 volumio[1162]: *** WARNING *** For more information see Oct 16 17:12:02 tz1 node[1162]: *** WARNING *** For more information see Oct 16 17:12:02 tz1 volumio[1162]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 16 17:12:02 tz1 node[1162]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 16 17:12:02 tz1 volumio[1162]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 17:12:02 tz1 node[1162]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 17:12:02 tz1 volumio[1162]: *** WARNING *** For more information see Oct 16 17:12:02 tz1 node[1162]: *** WARNING *** For more information see Oct 16 17:12:02 tz1 volumio[1162]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 16 17:12:02 tz1 volumio[1162]: info: Discovery: Started advertising with name: TZ1 Oct 16 17:12:02 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 17:12:02 tz1 volumio[1162]: info: Plugin ytmusic is not enabled Oct 16 17:12:02 tz1 volumio[1162]: info: Loading plugin "outputs"... Oct 16 17:12:02 tz1 volumio[1162]: info: Loading plugin "albumart"... Oct 16 17:12:02 tz1 volumio[1162]: info: Plugin example_plugin is not enabled Oct 16 17:12:02 tz1 volumio[1162]: info: Loading plugin "inputs"... Oct 16 17:12:02 tz1 volumio[1162]: info: Loading plugin "updater_comm"... Oct 16 17:12:03 tz1 volumio[1162]: info: Plugin mpdemulation is not enabled Oct 16 17:12:03 tz1 volumio[1162]: info: Loading plugin "rest_api"... Oct 16 17:12:03 tz1 volumio[1162]: info: Loading plugin "websocket"... Oct 16 17:12:03 tz1 volumio[1162]: info: Starting Socket.io Server version 2.3.0 Oct 16 17:12:03 tz1 volumio[1162]: info: Loading i18n strings for locale en Oct 16 17:12:03 tz1 volumio[1162]: Updating browse sources language Oct 16 17:12:03 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 17:12:03 tz1 volumio[1162]: Forking 3 albumart workers Oct 16 17:12:03 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 17:12:03 tz1 volumio[1162]: info: CoreCommandRouter::initPlayerControls Oct 16 17:12:03 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 17:12:03 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 17:12:03 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 17:12:03 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 17:12:03 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 17:12:03 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 17:12:03 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 17:12:03 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 17:12:04 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 17:12:04 tz1 volumio[1162]: Express server listening on port 3000 Oct 16 17:12:04 tz1 volumio[1162]: [Metrics] WebUI: 12s 507.31ms Oct 16 17:12:04 tz1 volumio[1162]: info: CoreStateMachine::resetVolumioState Oct 16 17:12:04 tz1 volumio[1162]: info: CoreStateMachine::getcurrentVolume Oct 16 17:12:04 tz1 volumio[1162]: info: CoreCommandRouter::volumioRetrievevolume Oct 16 17:12:04 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 17:12:04 tz1 volumio[1162]: info: Reloading queue from file Oct 16 17:12:04 tz1 volumio[1162]: info: CoreStateMachine::setRepeat null single undefined Oct 16 17:12:04 tz1 volumio[1162]: info: CoreStateMachine::pushState Oct 16 17:12:04 tz1 volumio[1162]: info: CorePlayQueue::getTrack 0 Oct 16 17:12:04 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 17:12:04 tz1 volumio[1162]: info: CoreCommandRouter::volumioPushState Oct 16 17:12:04 tz1 volumio[1162]: info: CoreStateMachine::setRandom null Oct 16 17:12:04 tz1 volumio[1162]: info: CoreStateMachine::pushState Oct 16 17:12:04 tz1 volumio[1162]: info: CorePlayQueue::getTrack 0 Oct 16 17:12:04 tz1 volumio[1162]: info: CoreCommandRouter::volumioPushState Oct 16 17:12:04 tz1 volumio[1162]: info: Setting Device type: Raspberry PI Oct 16 17:12:04 tz1 volumio[1162]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 16 17:12:04 tz1 volumio[1162]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Oct 16 17:12:04 tz1 volumio[1162]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 16 17:12:05 tz1 volumio[1162]: info: VolumeController:: Volume=86 Mute =false Oct 16 17:12:05 tz1 volumio[1162]: info: CoreStateMachine::pushState Oct 16 17:12:05 tz1 volumio[1162]: info: CorePlayQueue::getTrack 0 Oct 16 17:12:05 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 17:12:05 tz1 volumio[1162]: info: CoreCommandRouter::volumioPushState Oct 16 17:12:05 tz1 volumio[1162]: info: CoreStateMachine::updateTrackBlock Oct 16 17:12:05 tz1 volumio[1162]: info: CorePlayQueue::getTrackBlock Oct 16 17:12:05 tz1 volumio[1162]: info: CoreCommandRouter::volumioRetrievevolume Oct 16 17:12:05 tz1 volumio[1162]: info: Discovery: adding dbe187b1-722d-41bb-ba0e-977a8dc070b0 Oct 16 17:12:05 tz1 volumio[1162]: info: Discovery: Found device TZ1 Oct 16 17:12:05 tz1 volumio[1162]: info: CoreCommandRouter::volumioGetState Oct 16 17:12:05 tz1 volumio[1162]: info: CorePlayQueue::getTrack 0 Oct 16 17:12:05 tz1 volumio[1162]: info: Discovery: this is already registered, dbe187b1-722d-41bb-ba0e-977a8dc070b0 Oct 16 17:12:05 tz1 volumio[1162]: info: Discovery: Found device TZ1 Oct 16 17:12:05 tz1 volumio[1162]: info: CoreCommandRouter::volumioGetState Oct 16 17:12:05 tz1 volumio[1162]: info: CorePlayQueue::getTrack 0 Oct 16 17:12:05 tz1 volumio[1162]: info: VolumeController:: Volume=86 Mute =false Oct 16 17:12:05 tz1 volumio[1162]: info: CoreStateMachine::pushState Oct 16 17:12:05 tz1 volumio[1162]: info: CorePlayQueue::getTrack 0 Oct 16 17:12:05 tz1 volumio[1162]: info: CoreCommandRouter::volumioPushState Oct 16 17:12:05 tz1 volumio[1162]: Starting albumart workers Oct 16 17:12:05 tz1 volumio[1162]: Starting albumart workers Oct 16 17:12:05 tz1 volumio[1162]: Starting albumart workers Oct 16 17:12:09 tz1 sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 17:12:09 tz1 sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:12:09 tz1 sudo[1294]: pam_unix(sudo:session): session closed for user root Oct 16 17:12:09 tz1 sudo[1296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 17:12:09 tz1 sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:12:09 tz1 sudo[1296]: pam_unix(sudo:session): session closed for user root Oct 16 17:12:09 tz1 sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 16 17:12:09 tz1 sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:12:09 tz1 systemd[1]: Started UPnP Renderer front-end to MPD. Oct 16 17:12:09 tz1 sudo[1300]: pam_unix(sudo:session): session closed for user root Oct 16 17:12:09 tz1 volumio[1162]: info: Upmpdcli Daemon Started Oct 16 17:12:09 tz1 volumio[1162]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Oct 16 17:12:09 tz1 sudo[1207]: pam_unix(sudo:session): session closed for user root Oct 16 17:12:09 tz1 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 16 17:12:09 tz1 kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Oct 16 17:12:09 tz1 volumio[1162]: info: Cannot mount NAS tower at system boot, trial number 1 ,retrying in 5 seconds Oct 16 17:12:11 tz1 volumio-remote-updater[649]: [2024-10-16 17:12:11] [connect] Successful connection Oct 16 17:12:11 tz1 volumio-remote-updater[649]: [2024-10-16 17:12:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729062731 101 Oct 16 17:12:11 tz1 volumio[1162]: 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 Oct 16 17:12:12 tz1 sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 17:12:12 tz1 sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:12:12 tz1 sudo[1339]: pam_unix(sudo:session): session closed for user root Oct 16 17:12:12 tz1 sudo[1341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 17:12:12 tz1 sudo[1341]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:12:12 tz1 sudo[1341]: pam_unix(sudo:session): session closed for user root Oct 16 17:12:12 tz1 volumio[1162]: verbose: New Socket.io Connection to 172.24.116.82 from 172.24.116.59 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 16 17:12:12 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 17:12:12 tz1 volumio[1162]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 17:12:12 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 17:12:12 tz1 volumio[1162]: info: CoreCommandRouter::volumioGetState Oct 16 17:12:12 tz1 volumio[1162]: info: CorePlayQueue::getTrack 0 Oct 16 17:12:12 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 16 17:12:12 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 17:12:12 tz1 volumio[1162]: info: Received Get System Info Oct 16 17:12:12 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 17:12:12 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 17:12:12 tz1 volumio[1162]: info: Discovery: Getting this device information Oct 16 17:12:12 tz1 volumio[1162]: info: CoreCommandRouter::volumioGetState Oct 16 17:12:12 tz1 volumio[1162]: info: CorePlayQueue::getTrack 0 Oct 16 17:12:12 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 17:12:12 tz1 volumio[1162]: info: CoreCommandRouter::volumioGetState Oct 16 17:12:12 tz1 volumio[1162]: info: CorePlayQueue::getTrack 0 Oct 16 17:12:12 tz1 volumio[1162]: info: Listing playlists Oct 16 17:12:12 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 16 17:12:12 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 17:12:13 tz1 volumio[1162]: info: CoreCommandRouter::volumioGetState Oct 16 17:12:13 tz1 volumio[1162]: info: CorePlayQueue::getTrack 0 Oct 16 17:12:13 tz1 volumio[1162]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Oct 16 17:12:14 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 17:12:14 tz1 volumio[1162]: info: Received Get System Info Oct 16 17:12:14 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 17:12:14 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 17:12:14 tz1 volumio[1162]: info: Discovery: Getting this device information Oct 16 17:12:14 tz1 volumio[1162]: info: CoreCommandRouter::volumioGetState Oct 16 17:12:14 tz1 volumio[1162]: info: CorePlayQueue::getTrack 0 Oct 16 17:12:14 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 17:12:14 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 16 17:12:14 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 16 17:12:14 tz1 sudo[1345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=jacob,password=Legend14,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.160/Media/music /mnt/NAS/tower Oct 16 17:12:14 tz1 sudo[1345]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:12:14 tz1 kernel: CIFS: Attempting to mount \\192.168.0.160\Media Oct 16 17:12:15 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 17:12:15 tz1 volumio[1162]: info: Received Get System Info Oct 16 17:12:15 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 17:12:15 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 17:12:15 tz1 volumio[1162]: info: Discovery: Getting this device information Oct 16 17:12:15 tz1 volumio[1162]: info: CoreCommandRouter::volumioGetState Oct 16 17:12:15 tz1 volumio[1162]: info: CorePlayQueue::getTrack 0 Oct 16 17:12:15 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 17:12:15 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 16 17:12:17 tz1 volumio[1162]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 17:12:17 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 16 17:12:17 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 17:12:17 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 17:12:17 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 17:12:17 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 17:12:17 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 17:12:17 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 17:12:17 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 17:12:17 tz1 volumio[1162]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 17:12:17 tz1 volumio[1162]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 17:12:17 tz1 volumio[1162]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 17:12:17 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 17:12:17 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 16 17:12:19 tz1 volumio[1162]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Oct 16 17:12:21 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 17:12:22 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 16 17:12:24 tz1 volumio[1162]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 16 17:12:24 tz1 volumio[1162]: verbose: ControllerMpd::sendMpdCommand rescan Oct 16 17:12:24 tz1 volumio[1162]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 17:12:24 tz1 volumio[1162]: TypeError: Cannot read property 'then' of undefined Oct 16 17:12:24 tz1 volumio[1162]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:331:6) Oct 16 17:12:24 tz1 volumio[1162]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1885:15) Oct 16 17:12:24 tz1 volumio[1162]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1083:32) Oct 16 17:12:24 tz1 volumio[1162]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1073:26) Oct 16 17:12:24 tz1 volumio[1162]: at Socket.emit (events.js:315:20) Oct 16 17:12:24 tz1 volumio[1162]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 16 17:12:24 tz1 volumio[1162]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 16 17:12:24 tz1 volumio[1162]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 17:12:25 tz1 sudo[1345]: pam_unix(sudo:session): session closed for user root Oct 16 17:12:25 tz1 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 16 17:12:25 tz1 kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Oct 16 17:12:25 tz1 sudo[1364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-16 17:11 Oct 16 17:12:25 tz1 sudo[1364]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"