-- Logs begin at Thu 2024-10-03 20:39:51 CEST, end at Thu 2024-10-03 20:41:07 CEST. -- Oct 03 20:40:00 hifistreamer wpa_supplicant[1140]: wlan0: Trying to associate with SSID 'Doofy' Oct 03 20:40:00 hifistreamer wireless.js[878]: trying... Oct 03 20:40:00 hifistreamer sudo[1167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 03 20:40:00 hifistreamer sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:00 hifistreamer sudo[1167]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:01 hifistreamer wireless.js[878]: trying... Oct 03 20:40:01 hifistreamer sudo[1170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 03 20:40:01 hifistreamer sudo[1170]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:01 hifistreamer sudo[1170]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:02 hifistreamer wireless.js[878]: trying... Oct 03 20:40:02 hifistreamer sudo[1173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 03 20:40:02 hifistreamer sudo[1173]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:02 hifistreamer sudo[1173]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:03 hifistreamer sh[747]: eth0: leased 192.168.1.205 for 7200 seconds Oct 03 20:40:03 hifistreamer sh[747]: eth0: adding route to 192.168.1.0/24 Oct 03 20:40:03 hifistreamer sh[747]: eth0: adding default route via 192.168.1.10 Oct 03 20:40:03 hifistreamer dhcpcd[787]: eth0: leased 192.168.1.205 for 7200 seconds Oct 03 20:40:03 hifistreamer dhcpcd[787]: eth0: adding route to 192.168.1.0/24 Oct 03 20:40:03 hifistreamer dhcpcd[787]: eth0: adding default route via 192.168.1.10 Oct 03 20:40:03 hifistreamer avahi-daemon[879]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.205. Oct 03 20:40:03 hifistreamer avahi-daemon[879]: New relevant interface eth0.IPv4 for mDNS. Oct 03 20:40:03 hifistreamer avahi-daemon[879]: Registering new address record for 192.168.1.205 on eth0.IPv4. Oct 03 20:40:03 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:03 hifistreamer kernel: pcieport 0000:00:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Transmitter ID) Oct 03 20:40:03 hifistreamer kernel: pcieport 0000:00:00.0: device [14e4:2712] error status/mask=00001100/00002000 Oct 03 20:40:03 hifistreamer kernel: pcieport 0000:00:00.0: [ 8] Rollover Oct 03 20:40:03 hifistreamer kernel: pcieport 0000:00:00.0: [12] Timeout Oct 03 20:40:03 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:03 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:03 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:03 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:03 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:03 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:03 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:03 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:03 hifistreamer sh[747]: forked to background, child pid 1196 Oct 03 20:40:03 hifistreamer dhcpcd[787]: forked to background, child pid 1196 Oct 03 20:40:03 hifistreamer ifplugd(eth0)[1055]: client: ifup: interface eth0 already configured Oct 03 20:40:03 hifistreamer sh[747]: eth0=eth0 Oct 03 20:40:03 hifistreamer wpa_supplicant[1140]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 03 20:40:03 hifistreamer wireless.js[878]: trying... Oct 03 20:40:03 hifistreamer sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 03 20:40:03 hifistreamer sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:03 hifistreamer sudo[1223]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:03 hifistreamer ifplugd(eth0)[1055]: Program executed successfully. Oct 03 20:40:03 hifistreamer dhcpcd[1127]: eth0: leased 192.168.1.205 for 7200 seconds Oct 03 20:40:03 hifistreamer dhcpcd[1127]: eth0: adding route to 192.168.1.0/24 Oct 03 20:40:03 hifistreamer dhcpcd[1127]: eth0: adding default route via 192.168.1.10 Oct 03 20:40:03 hifistreamer dhcpcd[1127]: forked to background, child pid 1246 Oct 03 20:40:03 hifistreamer sudo[1126]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:03 hifistreamer nmbd[1026]: [2024/10/03 20:40:03.966829, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 03 20:40:03 hifistreamer systemd[1]: Started Samba NMB Daemon. Oct 03 20:40:03 hifistreamer nmbd[1026]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 03 20:40:03 hifistreamer nmbd[1026]: [2024/10/03 20:40:03.972515, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 03 20:40:03 hifistreamer nmbd[1026]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.205 for name WORKGROUP<1d>. Oct 03 20:40:03 hifistreamer nmbd[1026]: This response was from IP 192.168.1.4, reporting an IP address of 192.168.1.4. Oct 03 20:40:03 hifistreamer systemd[1]: Starting Samba Winbind Daemon... Oct 03 20:40:04 hifistreamer winbindd[1247]: [2024/10/03 20:40:04.061769, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 03 20:40:04 hifistreamer winbindd[1247]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 03 20:40:04 hifistreamer systemd[1]: Started Samba Winbind Daemon. Oct 03 20:40:04 hifistreamer winbindd[1247]: [2024/10/03 20:40:04.065778, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 03 20:40:04 hifistreamer winbindd[1247]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 03 20:40:04 hifistreamer systemd[1]: Starting Samba SMB Daemon... Oct 03 20:40:04 hifistreamer smbd[1252]: [2024/10/03 20:40:04.202236, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 03 20:40:04 hifistreamer systemd[1]: Started Samba SMB Daemon. Oct 03 20:40:04 hifistreamer smbd[1252]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 03 20:40:04 hifistreamer wireless.js[878]: trying... Oct 03 20:40:04 hifistreamer sudo[1261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 03 20:40:04 hifistreamer sudo[1261]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:04 hifistreamer sudo[1261]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:05 hifistreamer ntpd[1059]: Listen normally on 3 eth0 192.168.1.205:123 Oct 03 20:40:05 hifistreamer ntpd[1059]: new interface(s) found: waking up resolver Oct 03 20:40:05 hifistreamer wireless.js[878]: trying... Oct 03 20:40:05 hifistreamer sudo[1264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 03 20:40:05 hifistreamer sudo[1264]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:05 hifistreamer sudo[1264]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:05 hifistreamer volumio-remote-updater[876]: [2024-10-03 20:40:05] [info] asio async_connect error: system:111 (Connection refused) Oct 03 20:40:05 hifistreamer volumio-remote-updater[876]: [2024-10-03 20:40:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 03 20:40:05 hifistreamer volumio-remote-updater[876]: [2024-10-03 20:40:05] [error] handle_connect error: Underlying Transport Error Oct 03 20:40:06 hifistreamer wpa_supplicant[1140]: wlan0: Trying to associate with SSID 'Doofy' Oct 03 20:40:06 hifistreamer wireless.js[878]: trying... Oct 03 20:40:06 hifistreamer sudo[1267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 03 20:40:06 hifistreamer sudo[1267]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:06 hifistreamer sudo[1267]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:07 hifistreamer wireless.js[878]: trying... Oct 03 20:40:07 hifistreamer sudo[1270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 03 20:40:07 hifistreamer sudo[1270]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:07 hifistreamer sudo[1270]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:08 hifistreamer wireless.js[878]: trying... Oct 03 20:40:08 hifistreamer sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 03 20:40:08 hifistreamer sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:08 hifistreamer sudo[1273]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:09 hifistreamer wpa_supplicant[1140]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 03 20:40:09 hifistreamer wireless.js[878]: trying... Oct 03 20:40:09 hifistreamer sudo[1276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 03 20:40:09 hifistreamer sudo[1276]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:09 hifistreamer sudo[1276]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:10 hifistreamer wireless.js[878]: trying... Oct 03 20:40:10 hifistreamer sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 03 20:40:10 hifistreamer sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:10 hifistreamer sudo[1279]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:11 hifistreamer wpa_supplicant[1140]: wlan0: Trying to associate with SSID 'Doofy' Oct 03 20:40:11 hifistreamer wireless.js[878]: trying... Oct 03 20:40:11 hifistreamer sudo[1282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 03 20:40:11 hifistreamer sudo[1282]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:11 hifistreamer sudo[1282]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:12 hifistreamer wireless.js[878]: trying... Oct 03 20:40:12 hifistreamer sudo[1285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 03 20:40:12 hifistreamer sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:12 hifistreamer sudo[1285]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:13 hifistreamer wireless.js[878]: trying... Oct 03 20:40:13 hifistreamer sudo[1288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 03 20:40:13 hifistreamer sudo[1288]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:13 hifistreamer sudo[1288]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:14 hifistreamer wpa_supplicant[1140]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 03 20:40:14 hifistreamer wireless.js[878]: trying... Oct 03 20:40:14 hifistreamer sudo[1291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 03 20:40:14 hifistreamer sudo[1291]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:14 hifistreamer sudo[1291]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:15 hifistreamer wireless.js[878]: trying... Oct 03 20:40:15 hifistreamer sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 03 20:40:15 hifistreamer sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:15 hifistreamer sudo[1294]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:15 hifistreamer volumio-remote-updater[876]: [2024-10-03 20:40:15] [info] asio async_connect error: system:111 (Connection refused) Oct 03 20:40:15 hifistreamer volumio-remote-updater[876]: [2024-10-03 20:40:15] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 03 20:40:15 hifistreamer volumio-remote-updater[876]: [2024-10-03 20:40:15] [error] handle_connect error: Underlying Transport Error Oct 03 20:40:16 hifistreamer wpa_supplicant[1140]: wlan0: Trying to associate with SSID 'Doofy' Oct 03 20:40:16 hifistreamer wireless.js[878]: trying... Oct 03 20:40:16 hifistreamer sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 03 20:40:16 hifistreamer sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:16 hifistreamer sudo[1297]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:17 hifistreamer wireless.js[878]: trying... Oct 03 20:40:17 hifistreamer sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 03 20:40:17 hifistreamer sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:17 hifistreamer sudo[1300]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:18 hifistreamer wireless.js[878]: Overtime, starting plan B Oct 03 20:40:18 hifistreamer systemd[1]: Started Wireless Services. Oct 03 20:40:18 hifistreamer kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 03 20:40:18 hifistreamer systemd[1]: Started Volumio Backend Module. Oct 03 20:40:18 hifistreamer systemd[1]: Started Volumio Cpu Tweaker. Oct 03 20:40:18 hifistreamer systemd[1]: Reached target Multi-User System. Oct 03 20:40:18 hifistreamer systemd[1]: Reached target Graphical Interface. Oct 03 20:40:18 hifistreamer systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 03 20:40:18 hifistreamer systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 03 20:40:18 hifistreamer systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 03 20:40:18 hifistreamer systemd[1]: Startup finished in 10.884s (kernel) + 27.881s (userspace) = 38.765s. Oct 03 20:40:18 hifistreamer volumio-cpu-tweak[1306]: Setting RT Priority for mpd Oct 03 20:40:18 hifistreamer volumio-cpu-tweak[1306]: pid 35's current scheduling policy: SCHED_OTHER Oct 03 20:40:18 hifistreamer volumio-cpu-tweak[1306]: pid 35's current scheduling priority: 0 Oct 03 20:40:18 hifistreamer volumio-cpu-tweak[1306]: Setting MPD Affinity Oct 03 20:40:18 hifistreamer volumio-cpu-tweak[1306]: pid 3's current affinity mask: f Oct 03 20:40:18 hifistreamer volumio-cpu-tweak[1306]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 03 20:40:18 hifistreamer volumio-cpu-tweak[1306]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Oct 03 20:40:18 hifistreamer systemd[1]: volumio_cpu_tweak.service: Succeeded. Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Transmitter ID) Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: device [14e4:2712] error status/mask=00001100/00002000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: [ 8] Rollover Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: [12] Timeout Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: nvme 0000:01:00.0: PCIe Bus Error: severity=Corrected, type=Physical Layer, (Transmitter ID) Oct 03 20:40:18 hifistreamer kernel: nvme 0000:01:00.0: device [144d:a808] error status/mask=00001101/0000e000 Oct 03 20:40:18 hifistreamer kernel: nvme 0000:01:00.0: [ 0] RxErr Oct 03 20:40:18 hifistreamer kernel: nvme 0000:01:00.0: [ 8] Rollover Oct 03 20:40:18 hifistreamer kernel: nvme 0000:01:00.0: [12] Timeout Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Transmitter ID) Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: device [14e4:2712] error status/mask=00001040/00002000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: [ 6] BadTLP Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: [12] Timeout Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Receiver ID) Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: device [14e4:2712] error status/mask=00000040/00002000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: [ 6] BadTLP Oct 03 20:40:18 hifistreamer kernel: nvme 0000:01:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Transmitter ID) Oct 03 20:40:18 hifistreamer kernel: nvme 0000:01:00.0: device [144d:a808] error status/mask=00001100/0000e000 Oct 03 20:40:18 hifistreamer kernel: nvme 0000:01:00.0: [ 8] Rollover Oct 03 20:40:18 hifistreamer kernel: nvme 0000:01:00.0: [12] Timeout Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Transmitter ID) Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: device [14e4:2712] error status/mask=00001000/00002000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: [12] Timeout Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Transmitter ID) Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: device [14e4:2712] error status/mask=00001100/00002000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: [ 8] Rollover Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: [12] Timeout Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Receiver ID) Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: device [14e4:2712] error status/mask=00000040/00002000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: [ 6] BadTLP Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: nvme 0000:01:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Transmitter ID) Oct 03 20:40:18 hifistreamer kernel: nvme 0000:01:00.0: device [144d:a808] error status/mask=00001000/0000e000 Oct 03 20:40:18 hifistreamer kernel: nvme 0000:01:00.0: [12] Timeout Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Transmitter ID) Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: device [14e4:2712] error status/mask=00001100/00002000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: [ 8] Rollover Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: [12] Timeout Oct 03 20:40:18 hifistreamer kernel: nvme 0000:01:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Transmitter ID) Oct 03 20:40:18 hifistreamer kernel: nvme 0000:01:00.0: device [144d:a808] error status/mask=00001000/0000e000 Oct 03 20:40:18 hifistreamer kernel: nvme 0000:01:00.0: [12] Timeout Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:18 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:19 hifistreamer wpa_supplicant[1140]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 03 20:40:19 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:19 hifistreamer kernel: pcieport 0000:00:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Transmitter ID) Oct 03 20:40:19 hifistreamer kernel: pcieport 0000:00:00.0: device [14e4:2712] error status/mask=00001100/00002000 Oct 03 20:40:19 hifistreamer kernel: pcieport 0000:00:00.0: [ 8] Rollover Oct 03 20:40:19 hifistreamer kernel: pcieport 0000:00:00.0: [12] Timeout Oct 03 20:40:19 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:19 hifistreamer kernel: nvme 0000:01:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Transmitter ID) Oct 03 20:40:19 hifistreamer kernel: nvme 0000:01:00.0: device [144d:a808] error status/mask=00001000/0000e000 Oct 03 20:40:19 hifistreamer kernel: nvme 0000:01:00.0: [12] Timeout Oct 03 20:40:19 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:19 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:19 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:19 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:19 hifistreamer volumio[1305]: info: ------------------------------------------- Oct 03 20:40:19 hifistreamer volumio[1305]: info: ----- Volumio3 ---- Oct 03 20:40:19 hifistreamer volumio[1305]: info: ------------------------------------------- Oct 03 20:40:19 hifistreamer volumio[1305]: info: ----- System startup ---- Oct 03 20:40:19 hifistreamer volumio[1305]: info: ------------------------------------------- Oct 03 20:40:19 hifistreamer volumio[1305]: info: MYVOLUMIO Environment detected Oct 03 20:40:19 hifistreamer volumio[1305]: info: Plugin folders cleanup Oct 03 20:40:19 hifistreamer volumio[1305]: info: Scanning into folder /volumio/app/plugins/ Oct 03 20:40:19 hifistreamer volumio[1305]: info: Scanning category audio_interface Oct 03 20:40:19 hifistreamer volumio[1305]: info: Scanning category miscellanea Oct 03 20:40:19 hifistreamer volumio[1305]: info: Scanning category music_service Oct 03 20:40:19 hifistreamer volumio[1305]: info: Scanning category plugins.json Oct 03 20:40:19 hifistreamer volumio[1305]: info: Scanning category system_controller Oct 03 20:40:19 hifistreamer volumio[1305]: info: Scanning category user_interface Oct 03 20:40:19 hifistreamer volumio[1305]: info: Scanning into folder /data/plugins/ Oct 03 20:40:19 hifistreamer volumio[1305]: info: Scanning category system_controller Oct 03 20:40:19 hifistreamer volumio[1305]: info: Scanning category system_hardware Oct 03 20:40:19 hifistreamer volumio[1305]: info: Scanning category user_interface Oct 03 20:40:19 hifistreamer volumio[1305]: info: Plugin folders cleanup completed Oct 03 20:40:19 hifistreamer volumio[1305]: info: ------------------------------------------- Oct 03 20:40:19 hifistreamer volumio[1305]: info: ----- Core plugins startup ---- Oct 03 20:40:19 hifistreamer volumio[1305]: info: ------------------------------------------- Oct 03 20:40:19 hifistreamer volumio[1305]: info: Loading plugins from folder /volumio/app/plugins/ Oct 03 20:40:19 hifistreamer volumio[1305]: info: Adding plugin upnp to MyMusic Plugins Oct 03 20:40:19 hifistreamer volumio[1305]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 03 20:40:19 hifistreamer volumio[1305]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 03 20:40:19 hifistreamer volumio[1305]: info: Loading plugins from folder /data/plugins/ Oct 03 20:40:19 hifistreamer volumio[1305]: info: Loading plugin "system"... Oct 03 20:40:19 hifistreamer volumio[1305]: info: Loading plugin "appearance"... Oct 03 20:40:20 hifistreamer volumio[1305]: info: Loading plugin "network"... Oct 03 20:40:20 hifistreamer volumio[1305]: info: Refreshing Cached IP Addresses Oct 03 20:40:20 hifistreamer sudo[1337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 03 20:40:20 hifistreamer sudo[1337]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:20 hifistreamer sudo[1337]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:20 hifistreamer sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 03 20:40:20 hifistreamer sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:20 hifistreamer volumio[1305]: info: Loading plugin "services"... Oct 03 20:40:20 hifistreamer sudo[1339]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:20 hifistreamer volumio[1305]: info: Loading plugin "alsa_controller"... Oct 03 20:40:20 hifistreamer sudo[1343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 03 20:40:20 hifistreamer sudo[1343]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:20 hifistreamer sudo[1343]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:20 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 20:40:20 hifistreamer volumio[1305]: info: Loading plugin "wizard"... Oct 03 20:40:20 hifistreamer volumio[1305]: info: Loading plugin "networkfs"... Oct 03 20:40:20 hifistreamer volumio[1305]: info: Starting Udev Watcher for removable devices Oct 03 20:40:20 hifistreamer sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.17:/Music Flac /mnt/NAS/Music_Flac Oct 03 20:40:20 hifistreamer sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:20 hifistreamer volumio[1305]: info: Ignoring mount for partition: boot Oct 03 20:40:20 hifistreamer volumio[1305]: info: Ignoring mount for partition: volumio Oct 03 20:40:20 hifistreamer volumio[1305]: info: Ignoring mount for partition: volumio_data Oct 03 20:40:20 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 03 20:40:20 hifistreamer volumio[1305]: info: Loading plugin "volumio_command_line_client"... Oct 03 20:40:20 hifistreamer volumio[1305]: info: Plugin upnp is not enabled Oct 03 20:40:20 hifistreamer volumio[1305]: info: Loading plugin "my_music"... Oct 03 20:40:20 hifistreamer sudo[1355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.15:/sacd /mnt/NAS/SACD-DFS Oct 03 20:40:20 hifistreamer sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:20 hifistreamer volumio[1305]: info: Loading plugin "mpd"... Oct 03 20:40:20 hifistreamer sudo[1348]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:20 hifistreamer systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Oct 03 20:40:20 hifistreamer systemd[1]: Starting Preprocess NFS configuration... Oct 03 20:40:20 hifistreamer systemd[1]: Reached target Host and Network Name Lookups. Oct 03 20:40:20 hifistreamer systemd[1]: nfs-config.service: Succeeded. Oct 03 20:40:20 hifistreamer systemd[1]: Started Preprocess NFS configuration. Oct 03 20:40:20 hifistreamer systemd[1]: Starting NFS status monitor for NFSv2/3 locking.... Oct 03 20:40:20 hifistreamer systemd[1]: Starting Notify NFS peers of a restart... Oct 03 20:40:20 hifistreamer sm-notify[1369]: Version 1.3.3 starting Oct 03 20:40:20 hifistreamer systemd[1]: rpc-statd-notify.service: Succeeded. Oct 03 20:40:20 hifistreamer systemd[1]: Started Notify NFS peers of a restart. Oct 03 20:40:20 hifistreamer rpc.statd[1370]: Version 1.3.3 starting Oct 03 20:40:20 hifistreamer rpc.statd[1370]: Flags: TI-RPC Oct 03 20:40:20 hifistreamer systemd[1]: Started NFS status monitor for NFSv2/3 locking.. Oct 03 20:40:20 hifistreamer sudo[1355]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:20 hifistreamer volumio[1305]: info: Plugin upnp_browser is not enabled Oct 03 20:40:20 hifistreamer volumio[1305]: info: Loading plugin "alarm-clock"... Oct 03 20:40:20 hifistreamer volumio[1305]: info: Plugin airplay_emulation is not enabled Oct 03 20:40:20 hifistreamer volumio[1305]: info: Loading plugin "last_100"... Oct 03 20:40:20 hifistreamer volumio[1305]: info: Loading plugin "webradio"... Oct 03 20:40:20 hifistreamer volumio[1305]: info: Loading plugin "i2s_dacs"... Oct 03 20:40:20 hifistreamer volumio[1305]: info: Loading plugin "volumiodiscovery"... Oct 03 20:40:20 hifistreamer volumio[1305]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 03 20:40:20 hifistreamer node[1305]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 03 20:40:20 hifistreamer volumio[1305]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 03 20:40:20 hifistreamer node[1305]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 03 20:40:20 hifistreamer volumio[1305]: *** WARNING *** For more information see Oct 03 20:40:20 hifistreamer node[1305]: *** WARNING *** For more information see Oct 03 20:40:20 hifistreamer volumio[1305]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 03 20:40:20 hifistreamer node[1305]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 03 20:40:20 hifistreamer volumio[1305]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 03 20:40:20 hifistreamer node[1305]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 03 20:40:20 hifistreamer volumio[1305]: *** WARNING *** For more information see Oct 03 20:40:20 hifistreamer node[1305]: *** WARNING *** For more information see Oct 03 20:40:20 hifistreamer volumio[1305]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 03 20:40:20 hifistreamer volumio[1305]: info: Discovery: Started advertising with name: HiFiStreamer Oct 03 20:40:20 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 03 20:40:20 hifistreamer volumio[1305]: info: Loading plugin "now_playing"... Oct 03 20:40:20 hifistreamer volumio[1305]: info: Loading plugin "outputs"... Oct 03 20:40:20 hifistreamer volumio[1305]: info: Loading plugin "albumart"... Oct 03 20:40:20 hifistreamer volumio[1305]: info: Plugin example_plugin is not enabled Oct 03 20:40:20 hifistreamer volumio[1305]: info: Loading plugin "inputs"... Oct 03 20:40:20 hifistreamer volumio[1305]: info: Loading plugin "updater_comm"... Oct 03 20:40:21 hifistreamer volumio[1305]: info: Plugin mpdemulation is not enabled Oct 03 20:40:21 hifistreamer volumio[1305]: info: Loading plugin "rest_api"... Oct 03 20:40:21 hifistreamer volumio[1305]: info: Loading plugin "websocket"... Oct 03 20:40:21 hifistreamer volumio[1305]: info: Starting Socket.io Server version 2.3.0 Oct 03 20:40:21 hifistreamer volumio[1305]: info: Loading plugin "backup_restore"... Oct 03 20:40:21 hifistreamer volumio[1305]: Forking 3 albumart workers Oct 03 20:40:21 hifistreamer volumio[1305]: info: Applying required configuration parameters for plugin backup_restore Oct 03 20:40:21 hifistreamer volumio[1305]: info: Loading plugin "music_services_shield"... Oct 03 20:40:21 hifistreamer volumio[1305]: Starting albumart workers Oct 03 20:40:21 hifistreamer volumio[1305]: info: Applying required configuration parameters for plugin music_services_shield Oct 03 20:40:21 hifistreamer volumio[1305]: info: Plugin peppyspectrum is not enabled Oct 03 20:40:21 hifistreamer volumio[1305]: info: Loading plugin "touch_display"... Oct 03 20:40:21 hifistreamer volumio[1305]: Starting albumart workers Oct 03 20:40:21 hifistreamer volumio[1305]: Starting albumart workers Oct 03 20:40:21 hifistreamer volumio[1305]: info: Applying required configuration parameters for plugin touch_display Oct 03 20:40:21 hifistreamer volumio[1305]: info: Loading i18n strings for locale en Oct 03 20:40:21 hifistreamer wpa_supplicant[1140]: wlan0: Trying to associate with SSID 'Doofy' Oct 03 20:40:21 hifistreamer volumio[1305]: Updating browse sources language Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::initPlayerControls Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 20:40:21 hifistreamer volumio[1305]: Express server listening on port 3000 Oct 03 20:40:21 hifistreamer volumio[1305]: [Metrics] WebUI: 2s 560.61ms Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreStateMachine::resetVolumioState Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreStateMachine::getcurrentVolume Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioRetrievevolume Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreStateMachine::pushState Oct 03 20:40:21 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioPushState Oct 03 20:40:21 hifistreamer volumio[1305]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Oct 03 20:40:21 hifistreamer volumio[1305]: wlan0 Interface doesn't support scanning : Device or resource busy Oct 03 20:40:21 hifistreamer volumio[1305]: info: Cannot use regular scanning, forcing with ap-force Oct 03 20:40:21 hifistreamer sudo[1433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Oct 03 20:40:21 hifistreamer sudo[1433]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:21 hifistreamer sudo[1433]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:21 hifistreamer volumio[1305]: command failed: Resource temporarily unavailable (-11) Oct 03 20:40:21 hifistreamer volumio[1305]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Oct 03 20:40:21 hifistreamer volumio[1305]: command failed: Resource temporarily unavailable (-11) Oct 03 20:40:21 hifistreamer kernel: ieee80211 phy0: brcmf_cfg80211_scan: Connecting: status (3) Oct 03 20:40:21 hifistreamer volumio[1305]: verbose: New Socket.io Connection to 192.168.1.205 from 192.168.1.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Oct 03 20:40:21 hifistreamer volumio[1305]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 20:40:21 hifistreamer volumio[1305]: info: Reloading queue from file Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreStateMachine::setRepeat null single undefined Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreStateMachine::pushState Oct 03 20:40:21 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioPushState Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreStateMachine::setRandom null Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreStateMachine::pushState Oct 03 20:40:21 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioPushState Oct 03 20:40:21 hifistreamer volumio[1305]: info: Setting Device type: Raspberry PI Oct 03 20:40:21 hifistreamer volumio[1305]: info: Completed loading Core Plugins Oct 03 20:40:21 hifistreamer volumio[1305]: info: Preparing to generate the ALSA configuration file Oct 03 20:40:21 hifistreamer volumio[1305]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 03 20:40:21 hifistreamer volumio[1305]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Oct 03 20:40:21 hifistreamer volumio[1305]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 03 20:40:21 hifistreamer volumio[1305]: info: Asound.conf file unchanged, so no further update is needed Oct 03 20:40:21 hifistreamer volumio[1305]: info: Output device has changed, restarting MPD Oct 03 20:40:21 hifistreamer volumio[1305]: info: ___________ START PLUGINS ___________ Oct 03 20:40:21 hifistreamer sudo[1441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 20:40:21 hifistreamer sudo[1441]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:21 hifistreamer volumio[1305]: info: ControllerMpd::onStart: Initializing MPD Oct 03 20:40:21 hifistreamer volumio[1305]: info: Creating MPD Configuration file Oct 03 20:40:21 hifistreamer sudo[1441]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:21 hifistreamer sudo[1446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 20:40:21 hifistreamer sudo[1445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 20:40:21 hifistreamer sudo[1445]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:21 hifistreamer sudo[1446]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 03 20:40:21 hifistreamer volumio[1305]: info: [1727980821951] CoreMusicLibrary::Adding element Last_100 Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 03 20:40:21 hifistreamer volumio[1305]: info: [1727980821952] CoreMusicLibrary::Adding element Webradio Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 20:40:21 hifistreamer sudo[1450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 20:40:21 hifistreamer sudo[1450]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:21 hifistreamer volumio[1305]: info: Initializing BBC Radios Oct 03 20:40:21 hifistreamer systemd[1]: musicservicesshield.service: Succeeded. Oct 03 20:40:21 hifistreamer systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Oct 03 20:40:21 hifistreamer systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Oct 03 20:40:21 hifistreamer sudo[1446]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 20:40:21 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 20:40:21 hifistreamer volumio[1305]: info: [now-playing] ConfigUpdater: config is up to date. Oct 03 20:40:21 hifistreamer systemd[1]: Listening on mpd.socket. Oct 03 20:40:21 hifistreamer systemd[1]: Starting Music Player Daemon... Oct 03 20:40:21 hifistreamer systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 03 20:40:21 hifistreamer systemd[1]: mpd.service: Succeeded. Oct 03 20:40:21 hifistreamer systemd[1]: Stopped Music Player Daemon. Oct 03 20:40:21 hifistreamer systemd[1]: Starting Music Player Daemon... Oct 03 20:40:22 hifistreamer sudo[1465]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 20:40:22 hifistreamer sudo[1465]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:22 hifistreamer sudo[1465]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 03 20:40:22 hifistreamer sudo[1465]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:22 hifistreamer volumio[1305]: info: Loading i18n strings for locale en Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 20:40:22 hifistreamer volumio[1305]: info: Volumio Calling Home Oct 03 20:40:22 hifistreamer sudo[1494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Oct 03 20:40:22 hifistreamer sudo[1494]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:22 hifistreamer sudo[1503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Oct 03 20:40:22 hifistreamer sudo[1503]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:22 hifistreamer systemd[1]: Reloading. Oct 03 20:40:22 hifistreamer sudo[1508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Oct 03 20:40:22 hifistreamer sudo[1508]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:22 hifistreamer sudo[1510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 03 20:40:22 hifistreamer sudo[1510]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:22 hifistreamer volumio[1305]: info: [now-playing] Fetching API key... Oct 03 20:40:22 hifistreamer volumio[1305]: info: [now-playing] App is listening on port 4004. Oct 03 20:40:22 hifistreamer volumio[1305]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Oct 03 20:40:22 hifistreamer volumio[1305]: info: Discovery: adding 720afa6a-badd-481c-8a91-deb8b3afafbf Oct 03 20:40:22 hifistreamer volumio[1305]: info: Discovery: Found device HiFiStreamer Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:40:22 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:22 hifistreamer volumio[1305]: info: touch_display: Backlight interface detected. Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:40:22 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:22 hifistreamer volumio[1305]: info: MPD Permissions set Oct 03 20:40:22 hifistreamer volumio[1305]: info: MPD Permissions set Oct 03 20:40:22 hifistreamer 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. Oct 03 20:40:22 hifistreamer volumio[1305]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:22 hifistreamer systemd[1]: serial-getty@ttyAMA10.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 03 20:40:22 hifistreamer systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Oct 03 20:40:22 hifistreamer volumio[1305]: info: Received Get System Info Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 20:40:22 hifistreamer volumio[1305]: info: Discovery: Getting this device information Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:40:22 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:40:22 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetVisibleSources Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:40:22 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:40:22 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 03 20:40:22 hifistreamer volumio[1305]: info: Received Get System Info Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 20:40:22 hifistreamer volumio[1305]: info: Discovery: Getting this device information Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:40:22 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:40:22 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:22 hifistreamer volumio[1305]: info: Listing playlists Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetQueue Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreStateMachine::getQueue Oct 03 20:40:22 hifistreamer volumio[1305]: info: CorePlayQueue::getQueue Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 03 20:40:22 hifistreamer volumio[1305]: info: Volumio called home Oct 03 20:40:22 hifistreamer volumio[1305]: info: touch_display: Raspberry Pi Foundation touch screen detected. Oct 03 20:40:22 hifistreamer sudo[1503]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:22 hifistreamer sudo[1544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Oct 03 20:40:22 hifistreamer sudo[1544]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:22 hifistreamer systemd[1]: Reloading. Oct 03 20:40:22 hifistreamer sudo[1546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Oct 03 20:40:22 hifistreamer sudo[1546]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:22 hifistreamer sudo[1494]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:22 hifistreamer sudo[1548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/4-0045/brightness Oct 03 20:40:22 hifistreamer sudo[1548]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:22 hifistreamer sudo[1544]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:22 hifistreamer sudo[1548]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:22 hifistreamer sudo[1546]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:40:22 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:22 hifistreamer volumio[1305]: info: touch_display: File permissions for backlight brightness control set. Oct 03 20:40:22 hifistreamer volumio[1305]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Oct 03 20:40:22 hifistreamer volumio[1305]: info: touch_display: systemctl disable getty@tty1.service succeeded. Oct 03 20:40:22 hifistreamer volumio[1305]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Oct 03 20:40:22 hifistreamer sudo[1566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Oct 03 20:40:22 hifistreamer sudo[1566]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:22 hifistreamer mpd[1477]: Oct 03 20:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 03 20:40:22 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 03 20:40:22 hifistreamer 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. Oct 03 20:40:22 hifistreamer systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Oct 03 20:40:22 hifistreamer systemd[1]: systemd-fsckd.service: Succeeded. Oct 03 20:40:22 hifistreamer sudo[1510]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:22 hifistreamer volumio[1305]: info: touch_display: systemctl daemon-reload succeeded. Oct 03 20:40:22 hifistreamer sudo[1572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Oct 03 20:40:22 hifistreamer sudo[1572]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:22 hifistreamer sudo[1508]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:22 hifistreamer volumio[1305]: info: touch_display: systemctl stop getty@tty1.service succeeded. Oct 03 20:40:22 hifistreamer systemd[1]: Started Volumio Kiosk. Oct 03 20:40:23 hifistreamer sudo[1572]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:23 hifistreamer volumio[1305]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Oct 03 20:40:23 hifistreamer volumio[1305]: info: touch_display: Volumio Kiosk started. Oct 03 20:40:23 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:40:23 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:23 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:40:23 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:23 hifistreamer volumio[1305]: info: touch_display: X display number found: Oct 03 20:40:23 hifistreamer volumio[1305]: info: [now-playing] OpenWeatherMap API key obtained. Oct 03 20:40:23 hifistreamer startx[1574]: X.Org X Server 1.20.4 Oct 03 20:40:23 hifistreamer startx[1574]: X Protocol Version 11, Revision 0 Oct 03 20:40:23 hifistreamer startx[1574]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Oct 03 20:40:23 hifistreamer startx[1574]: Current Operating System: Linux hifistreamer 6.1.69-v8+ #1710 SMP PREEMPT Thu Dec 21 13:21:40 GMT 2023 aarch64 Oct 03 20:40:23 hifistreamer startx[1574]: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 smsc95xx.macaddr=2C:CF:67:62:01:BD vc_mem.mem_base=0x3fc00000 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=ttyAMA10,115200 console=tty1 imgpart=UUID=8543fa12-4125-4451-a534-cec5480e0606 imgfile=/volumio_current.sqsh bootpart=UUID=9C48-E5A3 datapart=UUID=f7cca1c6-391b-47a8-9c19-3b1948539def 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 Oct 03 20:40:23 hifistreamer startx[1574]: Build Date: 04 April 2023 07:50:56AM Oct 03 20:40:23 hifistreamer startx[1574]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Oct 03 20:40:23 hifistreamer startx[1574]: Current version of pixman: 0.36.0 Oct 03 20:40:23 hifistreamer startx[1574]: Before reporting problems, check http://wiki.x.org Oct 03 20:40:23 hifistreamer startx[1574]: to make sure that you have the latest version. Oct 03 20:40:23 hifistreamer startx[1574]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 03 20:40:23 hifistreamer startx[1574]: (++) from command line, (!!) notice, (II) informational, Oct 03 20:40:23 hifistreamer startx[1574]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 03 20:40:23 hifistreamer startx[1574]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Oct 3 20:40:23 2024 Oct 03 20:40:23 hifistreamer startx[1574]: (==) Using config directory: "/etc/X11/xorg.conf.d" Oct 03 20:40:23 hifistreamer startx[1574]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 03 20:40:23 hifistreamer startx[1574]: MESA-LOADER: failed to open drm-rp1-dsi (search paths /usr/lib/arm-linux-gnueabihf/dri:\$${ORIGIN}/dri:/usr/lib/dri) Oct 03 20:40:23 hifistreamer startx[1574]: failed to load driver: drm-rp1-dsi Oct 03 20:40:23 hifistreamer volumio[1305]: info: touch_display: X display number found: 0 Oct 03 20:40:23 hifistreamer volumio[1305]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=59.248574&lon=10.35971: Error: Response error: 401 - Unauthorized Oct 03 20:40:23 hifistreamer volumio[1305]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 03 20:40:23 hifistreamer volumio[1305]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 03 20:40:23 hifistreamer volumio[1305]: at async Promise.all (index 1) Oct 03 20:40:23 hifistreamer volumio[1305]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Oct 03 20:40:23 hifistreamer volumio[1305]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Oct 03 20:40:23 hifistreamer volumio[1305]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Oct 03 20:40:23 hifistreamer volumio[1305]: info: [now-playing] Fetching API key... Oct 03 20:40:23 hifistreamer volumio[1305]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=59.248574&lon=10.35971: Error: Response error: 401 - Unauthorized Oct 03 20:40:23 hifistreamer volumio[1305]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 03 20:40:23 hifistreamer volumio[1305]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 03 20:40:23 hifistreamer volumio[1305]: at async Promise.all (index 0) Oct 03 20:40:23 hifistreamer volumio[1305]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 03 20:40:23 hifistreamer volumio[1305]: info: touch_display: X display number found: 0 Oct 03 20:40:23 hifistreamer kernel: broken atomic modeset userspace detected, disabling atomic Oct 03 20:40:23 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 03 20:40:23 hifistreamer startx[1574]: V3D 7.1 not supported by this version of Mesa. Oct 03 20:40:23 hifistreamer volumio[1305]: info: [now-playing] OpenWeatherMap API key obtained. Oct 03 20:40:23 hifistreamer kernel: broken atomic modeset userspace detected, disabling atomic Oct 03 20:40:23 hifistreamer startx[1574]: (II) modeset(G0): Initializing kms color map for depth 24, 8 bpc. Oct 03 20:40:23 hifistreamer startx[1574]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Oct 03 20:40:23 hifistreamer volumio[1305]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=59.248574&lon=10.35971: Error: Response error: 401 - Unauthorized Oct 03 20:40:23 hifistreamer volumio[1305]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 03 20:40:23 hifistreamer volumio[1305]: at runMicrotasks () Oct 03 20:40:23 hifistreamer volumio[1305]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 03 20:40:23 hifistreamer volumio[1305]: at async Promise.all (index 0) Oct 03 20:40:23 hifistreamer volumio[1305]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Oct 03 20:40:23 hifistreamer volumio[1305]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Oct 03 20:40:23 hifistreamer volumio[1305]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Oct 03 20:40:23 hifistreamer volumio[1305]: (node:1305) UnhandledPromiseRejectionWarning: Error: Response error: 401 - Unauthorized Oct 03 20:40:23 hifistreamer volumio[1305]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 03 20:40:23 hifistreamer volumio[1305]: at runMicrotasks () Oct 03 20:40:23 hifistreamer volumio[1305]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 03 20:40:23 hifistreamer volumio[1305]: at async Promise.all (index 0) Oct 03 20:40:23 hifistreamer volumio[1305]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Oct 03 20:40:23 hifistreamer volumio[1305]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Oct 03 20:40:23 hifistreamer volumio[1305]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Oct 03 20:40:23 hifistreamer volumio[1305]: (Use `node --trace-warnings ...` to show where the warning was created) Oct 03 20:40:23 hifistreamer volumio[1305]: (node:1305) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Oct 03 20:40:23 hifistreamer volumio[1305]: (node:1305) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Oct 03 20:40:23 hifistreamer volumio[1305]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=59.248574&lon=10.35971: Error: Response error: 401 - Unauthorized Oct 03 20:40:23 hifistreamer volumio[1305]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 03 20:40:23 hifistreamer volumio[1305]: at runMicrotasks () Oct 03 20:40:23 hifistreamer volumio[1305]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 03 20:40:23 hifistreamer volumio[1305]: at async Promise.all (index 1) Oct 03 20:40:23 hifistreamer systemd[1]: systemd-hostnamed.service: Succeeded. Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Transmitter ID) Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: device [14e4:2712] error status/mask=00001000/00002000 Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: [12] Timeout Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Transmitter ID) Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: device [14e4:2712] error status/mask=00001140/00002000 Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: [ 6] BadTLP Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: [ 8] Rollover Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: [12] Timeout Oct 03 20:40:23 hifistreamer kernel: nvme 0000:01:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Transmitter ID) Oct 03 20:40:23 hifistreamer kernel: nvme 0000:01:00.0: device [144d:a808] error status/mask=00001100/0000e000 Oct 03 20:40:23 hifistreamer kernel: nvme 0000:01:00.0: [ 8] Rollover Oct 03 20:40:23 hifistreamer kernel: nvme 0000:01:00.0: [12] Timeout Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:23 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:24 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:40:24 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:24 hifistreamer volumio[1305]: info: touch_display: Setting screensaver timeout to 120000 seconds. Oct 03 20:40:24 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:24 hifistreamer kernel: pcieport 0000:00:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Transmitter ID) Oct 03 20:40:24 hifistreamer kernel: pcieport 0000:00:00.0: device [14e4:2712] error status/mask=00001140/00002000 Oct 03 20:40:24 hifistreamer kernel: pcieport 0000:00:00.0: [ 6] BadTLP Oct 03 20:40:24 hifistreamer kernel: pcieport 0000:00:00.0: [ 8] Rollover Oct 03 20:40:24 hifistreamer kernel: pcieport 0000:00:00.0: [12] Timeout Oct 03 20:40:24 hifistreamer kernel: nvme 0000:01:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Transmitter ID) Oct 03 20:40:24 hifistreamer kernel: nvme 0000:01:00.0: device [144d:a808] error status/mask=00001100/0000e000 Oct 03 20:40:24 hifistreamer kernel: nvme 0000:01:00.0: [ 8] Rollover Oct 03 20:40:24 hifistreamer kernel: nvme 0000:01:00.0: [12] Timeout Oct 03 20:40:24 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:24 hifistreamer kernel: pcieport 0000:00:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Receiver ID) Oct 03 20:40:24 hifistreamer kernel: pcieport 0000:00:00.0: device [14e4:2712] error status/mask=00000040/00002000 Oct 03 20:40:24 hifistreamer kernel: pcieport 0000:00:00.0: [ 6] BadTLP Oct 03 20:40:24 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:24 hifistreamer kernel: nvme 0000:01:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Transmitter ID) Oct 03 20:40:24 hifistreamer kernel: nvme 0000:01:00.0: device [144d:a808] error status/mask=00001100/0000e000 Oct 03 20:40:24 hifistreamer kernel: nvme 0000:01:00.0: [ 8] Rollover Oct 03 20:40:24 hifistreamer kernel: nvme 0000:01:00.0: [12] Timeout Oct 03 20:40:24 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:24 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:24 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:24 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:24 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:24 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:24 hifistreamer wpa_supplicant[1140]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 03 20:40:24 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 03 20:40:25 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 20:40:25 hifistreamer volumio[1305]: info: Discovery: Getting this device information Oct 03 20:40:25 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:40:25 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:25 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 20:40:25 hifistreamer systemd[1]: Started Music Player Daemon. Oct 03 20:40:25 hifistreamer sudo[1450]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:25 hifistreamer sudo[1445]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:25 hifistreamer volumio[1305]: info: Completed starting Core Plugins Oct 03 20:40:25 hifistreamer volumio[1305]: info: ------------------------------------------- Oct 03 20:40:25 hifistreamer volumio[1305]: info: ----- MyVolumio plugins startup ---- Oct 03 20:40:25 hifistreamer volumio[1305]: info: ------------------------------------------- Oct 03 20:40:25 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 03 20:40:25 hifistreamer volumio[1305]: info: MPD running with PID1477 Oct 03 20:40:25 hifistreamer volumio[1305]: ,establishing connection Oct 03 20:40:25 hifistreamer systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Oct 03 20:40:25 hifistreamer volumio[1305]: error: MPD error: The expression evaluated to a falsy value: Oct 03 20:40:25 hifistreamer volumio[1305]: assert.ok(self.idling) Oct 03 20:40:25 hifistreamer volumio[1305]: error: The expression evaluated to a falsy value: Oct 03 20:40:25 hifistreamer volumio[1305]: assert.ok(self.idling) Oct 03 20:40:25 hifistreamer volumio[1305]: error: MPD error: The expression evaluated to a falsy value: Oct 03 20:40:25 hifistreamer volumio[1305]: assert.ok(self.idling) Oct 03 20:40:25 hifistreamer volumio[1305]: error: The expression evaluated to a falsy value: Oct 03 20:40:25 hifistreamer volumio[1305]: assert.ok(self.idling) Oct 03 20:40:25 hifistreamer volumio[1305]: error: updateQueue error: null Oct 03 20:40:25 hifistreamer volumio[1805]: cset: --> shielding system active with Oct 03 20:40:25 hifistreamer volumio[1805]: cset: "system" cpuset of CPUSPEC(0-1) with 196 tasks running Oct 03 20:40:25 hifistreamer volumio[1805]: cset: "user" cpuset of CPUSPEC(2-3) with 3 tasks running Oct 03 20:40:25 hifistreamer systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Oct 03 20:40:25 hifistreamer systemd[1]: Reloading. Oct 03 20:40:25 hifistreamer volumio[1305]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=59.248574&lon=10.35971: Error: Response error: 401 - Unauthorized Oct 03 20:40:25 hifistreamer volumio[1305]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 03 20:40:25 hifistreamer volumio[1305]: at runMicrotasks () Oct 03 20:40:25 hifistreamer volumio[1305]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 03 20:40:25 hifistreamer volumio[1305]: at async Promise.all (index 0) Oct 03 20:40:25 hifistreamer volumio[1305]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Oct 03 20:40:25 hifistreamer volumio[1305]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Oct 03 20:40:25 hifistreamer volumio[1305]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Oct 03 20:40:25 hifistreamer volumio[1305]: info: [now-playing] Fetching API key... Oct 03 20:40:25 hifistreamer volumio[1305]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=59.248574&lon=10.35971: Error: Response error: 401 - Unauthorized Oct 03 20:40:25 hifistreamer volumio[1305]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 03 20:40:25 hifistreamer volumio[1305]: at runMicrotasks () Oct 03 20:40:25 hifistreamer volumio[1305]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 03 20:40:25 hifistreamer volumio[1305]: at async Promise.all (index 1) Oct 03 20:40:25 hifistreamer volumio[1305]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 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 Oct 03 20:40:25 hifistreamer 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. Oct 03 20:40:25 hifistreamer volumio-remote-updater[876]: [2024-10-03 20:40:25] [connect] Successful connection Oct 03 20:40:25 hifistreamer volumio[1305]: 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: 5 Oct 03 20:40:25 hifistreamer volumio-remote-updater[876]: [2024-10-03 20:40:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1727980825 101 Oct 03 20:40:26 hifistreamer volumio[1305]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Oct 03 20:40:26 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Oct 03 20:40:26 hifistreamer systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Oct 03 20:40:26 hifistreamer volumio[1305]: info: Listing playlists Oct 03 20:40:26 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetQueue Oct 03 20:40:26 hifistreamer volumio[1305]: info: CoreStateMachine::getQueue Oct 03 20:40:26 hifistreamer volumio[1305]: info: CorePlayQueue::getQueue Oct 03 20:40:26 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetVisibleSources Oct 03 20:40:26 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 20:40:26 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:40:26 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:26 hifistreamer sudo[1566]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:26 hifistreamer volumio[1305]: info: succeeded Oct 03 20:40:26 hifistreamer volumio[1305]: info: [now-playing] OpenWeatherMap API key obtained. Oct 03 20:40:26 hifistreamer volumio[1305]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=59.248574&lon=10.35971: Error: Response error: 401 - Unauthorized Oct 03 20:40:26 hifistreamer volumio[1305]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 03 20:40:26 hifistreamer volumio[1305]: at runMicrotasks () Oct 03 20:40:26 hifistreamer volumio[1305]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 03 20:40:26 hifistreamer volumio[1305]: at async Promise.all (index 1) Oct 03 20:40:26 hifistreamer volumio[1305]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Oct 03 20:40:26 hifistreamer volumio[1305]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Oct 03 20:40:26 hifistreamer volumio[1305]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Oct 03 20:40:26 hifistreamer volumio[1305]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not fetch weather: Response error: 401 - Unauthorized Oct 03 20:40:26 hifistreamer volumio[1305]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:114:19) Oct 03 20:40:26 hifistreamer volumio[1305]: at runMicrotasks () Oct 03 20:40:26 hifistreamer volumio[1305]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 03 20:40:26 hifistreamer volumio[1305]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:28) Oct 03 20:40:26 hifistreamer volumio[1305]: (node:1305) UnhandledPromiseRejectionWarning: Error: Response error: 401 - Unauthorized Oct 03 20:40:26 hifistreamer volumio[1305]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 03 20:40:26 hifistreamer volumio[1305]: at runMicrotasks () Oct 03 20:40:26 hifistreamer volumio[1305]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 03 20:40:26 hifistreamer volumio[1305]: at async Promise.all (index 1) Oct 03 20:40:26 hifistreamer volumio[1305]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Oct 03 20:40:26 hifistreamer volumio[1305]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Oct 03 20:40:26 hifistreamer volumio[1305]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Oct 03 20:40:26 hifistreamer volumio[1305]: (node:1305) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 4) Oct 03 20:40:26 hifistreamer volumio[1305]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=59.248574&lon=10.35971: Error: Response error: 401 - Unauthorized Oct 03 20:40:26 hifistreamer volumio[1305]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 03 20:40:26 hifistreamer volumio[1305]: at runMicrotasks () Oct 03 20:40:26 hifistreamer volumio[1305]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 03 20:40:26 hifistreamer volumio[1305]: at async Promise.all (index 0) Oct 03 20:40:27 hifistreamer wpa_supplicant[1140]: wlan0: Trying to associate with SSID 'Doofy' Oct 03 20:40:27 hifistreamer volumio[1305]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 03 20:40:27 hifistreamer volumio[1305]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Oct 03 20:40:27 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Oct 03 20:40:27 hifistreamer volumio[1305]: info: Listing playlists Oct 03 20:40:27 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetQueue Oct 03 20:40:27 hifistreamer volumio[1305]: info: CoreStateMachine::getQueue Oct 03 20:40:27 hifistreamer volumio[1305]: info: CorePlayQueue::getQueue Oct 03 20:40:27 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetVisibleSources Oct 03 20:40:27 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 20:40:27 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:40:27 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:27 hifistreamer volumio[1305]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Oct 03 20:40:27 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Oct 03 20:40:27 hifistreamer volumio[1305]: info: Listing playlists Oct 03 20:40:27 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetQueue Oct 03 20:40:27 hifistreamer volumio[1305]: info: CoreStateMachine::getQueue Oct 03 20:40:27 hifistreamer volumio[1305]: info: CorePlayQueue::getQueue Oct 03 20:40:27 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetVisibleSources Oct 03 20:40:27 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 20:40:27 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:40:27 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:29 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:40:29 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:29 hifistreamer volumio[1305]: info: Listing playlists Oct 03 20:40:29 hifistreamer volumio[1305]: info: Listing playlists Oct 03 20:40:29 hifistreamer wpa_supplicant[1140]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 03 20:40:32 hifistreamer volumio[1305]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 03 20:40:32 hifistreamer wpa_supplicant[1140]: wlan0: Trying to associate with SSID 'Doofy' Oct 03 20:40:35 hifistreamer wpa_supplicant[1140]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 03 20:40:36 hifistreamer volumio[1305]: info: Adding plugin bluetooth to MyMusic Plugins Oct 03 20:40:36 hifistreamer volumio[1305]: info: Adding plugin multiroom to MyMusic Plugins Oct 03 20:40:36 hifistreamer volumio[1305]: info: Adding plugin metavolumio to MyMusic Plugins Oct 03 20:40:36 hifistreamer volumio[1305]: info: Adding plugin cd_controller to MyMusic Plugins Oct 03 20:40:36 hifistreamer volumio[1305]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 03 20:40:36 hifistreamer volumio[1305]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 03 20:40:36 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 20:40:36 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 20:40:36 hifistreamer volumio[1305]: info: Starting MyVolumio Remote Streaming Endpoints Oct 03 20:40:36 hifistreamer volumio[1305]: info: MyVolumio login type: Token Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 03 20:40:36 hifistreamer volumio[1305]: info: Starting Streaming Service Transparent Proxy Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 03 20:40:36 hifistreamer volumio[1305]: info: Streaming services startup Oct 03 20:40:36 hifistreamer volumio[1305]: info: Starting Streaming Daemon Oct 03 20:40:36 hifistreamer sudo[2029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 03 20:40:36 hifistreamer sudo[2029]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:36 hifistreamer volumio[1305]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 03 20:40:36 hifistreamer sudo[2029]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:37 hifistreamer volumio[1305]: STREAMING PROXY: Starting server on port 3245 Oct 03 20:40:37 hifistreamer volumio[1305]: Node JS runtime: 14 Oct 03 20:40:37 hifistreamer volumio[1305]: error: Cannot start Volumio Streaming Daemon Oct 03 20:40:37 hifistreamer volumio[1305]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 03 20:40:37 hifistreamer volumio[1305]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 03 20:40:37 hifistreamer wpa_supplicant[1140]: wlan0: Trying to associate with SSID 'Doofy' Oct 03 20:40:40 hifistreamer wpa_supplicant[1140]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 03 20:40:40 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:40:40 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:40 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:40 hifistreamer kernel: pcieport 0000:00:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Transmitter ID) Oct 03 20:40:40 hifistreamer kernel: pcieport 0000:00:00.0: device [14e4:2712] error status/mask=00001100/00002000 Oct 03 20:40:40 hifistreamer kernel: pcieport 0000:00:00.0: [ 8] Rollover Oct 03 20:40:40 hifistreamer kernel: pcieport 0000:00:00.0: [12] Timeout Oct 03 20:40:40 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:40 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:40 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:40 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:40 hifistreamer kernel: pcieport 0000:00:00.0: AER: Multiple Corrected error received: 0000:00:00.0 Oct 03 20:40:40 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:43 hifistreamer volumio[1305]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 03 20:40:43 hifistreamer wpa_supplicant[1140]: wlan0: Trying to associate with SSID 'Doofy' Oct 03 20:40:46 hifistreamer wpa_supplicant[1140]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 03 20:40:48 hifistreamer volumio[1305]: info: MyVolumio token set successfully Oct 03 20:40:48 hifistreamer volumio[1305]: info: MYVOLUMIO: Adding device Oct 03 20:40:48 hifistreamer volumio[1305]: info: MYVOLUMIO: Evaluating Server Oct 03 20:40:49 hifistreamer wpa_supplicant[1140]: wlan0: Trying to associate with SSID 'Doofy' Oct 03 20:40:51 hifistreamer volumio[1305]: info: MyVolumio status changed Oct 03 20:40:51 hifistreamer volumio[1305]: info: Streaming services startup Oct 03 20:40:51 hifistreamer volumio[1305]: info: Starting Streaming Daemon Oct 03 20:40:51 hifistreamer volumio[1305]: info: Removing browser output: myVolumio user plan is not superstar Oct 03 20:40:51 hifistreamer volumio[1305]: info: Removing audio output: Oct 03 20:40:51 hifistreamer volumio[1305]: info: Stoppping Tunnel 1 Oct 03 20:40:51 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 03 20:40:51 hifistreamer sudo[2055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 03 20:40:51 hifistreamer sudo[2055]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:51 hifistreamer sudo[2053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 03 20:40:51 hifistreamer sudo[2053]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 20:40:51 hifistreamer sudo[2053]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:51 hifistreamer sudo[2055]: pam_unix(sudo:session): session closed for user root Oct 03 20:40:52 hifistreamer wpa_supplicant[1140]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 03 20:40:53 hifistreamer volumio[1305]: info: Remote SSH Stopped Oct 03 20:40:53 hifistreamer volumio[1305]: error: Cannot start Volumio Streaming Daemon Oct 03 20:40:53 hifistreamer volumio[1305]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 03 20:40:53 hifistreamer volumio[1305]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 03 20:40:53 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 20:40:53 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 20:40:53 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 03 20:40:53 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:53 hifistreamer kernel: pcieport 0000:00:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Transmitter ID) Oct 03 20:40:53 hifistreamer kernel: pcieport 0000:00:00.0: device [14e4:2712] error status/mask=00001100/00002000 Oct 03 20:40:53 hifistreamer kernel: pcieport 0000:00:00.0: [ 8] Rollover Oct 03 20:40:53 hifistreamer kernel: pcieport 0000:00:00.0: [12] Timeout Oct 03 20:40:53 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:53 hifistreamer kernel: nvme 0000:01:00.0: PCIe Bus Error: severity=Corrected, type=Physical Layer, (Receiver ID) Oct 03 20:40:53 hifistreamer kernel: nvme 0000:01:00.0: device [144d:a808] error status/mask=00008001/0000e000 Oct 03 20:40:53 hifistreamer kernel: nvme 0000:01:00.0: [ 0] RxErr Oct 03 20:40:53 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:53 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:53 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:53 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:53 hifistreamer kernel: pcieport 0000:00:00.0: AER: Corrected error received: 0000:00:00.0 Oct 03 20:40:53 hifistreamer kernel: pcieport 0000:00:00.0: AER: can't find device of ID0000 Oct 03 20:40:53 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:40:53 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:54 hifistreamer volumio[1305]: info: Setting Geolocation for MyVolumio to eu2 Oct 03 20:40:54 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 20:40:54 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 20:40:54 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 20:40:55 hifistreamer volumio-remote-updater[876]: No test mode Oct 03 20:40:55 hifistreamer volumio-remote-updater[876]: No alpha test mode Oct 03 20:40:55 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 03 20:40:55 hifistreamer volumio[1305]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 03 20:40:55 hifistreamer volumio[1305]: info: Updating MyVolumio device info Oct 03 20:40:55 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 20:40:55 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 20:40:55 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 20:40:55 hifistreamer wpa_supplicant[1140]: wlan0: Trying to associate with SSID 'Doofy' Oct 03 20:40:55 hifistreamer volumio[1305]: info: Listing playlists Oct 03 20:40:55 hifistreamer volumio[1305]: info: Listing playlists Oct 03 20:40:56 hifistreamer volumio[1305]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 03 20:40:56 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 03 20:40:56 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:56 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:56 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 03 20:40:56 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 03 20:40:56 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 03 20:40:56 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 03 20:40:56 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetBrowseSources Oct 03 20:40:56 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 20:40:57 hifistreamer volumio[1305]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 03 20:40:57 hifistreamer volumio[1305]: info: Completed starting MyVolumio Plugin Oct 03 20:40:57 hifistreamer volumio[1305]: info: BOOT COMPLETED Oct 03 20:40:57 hifistreamer volumio[1305]: [Metrics] CommandRouter: 37s 934.78ms Oct 03 20:40:57 hifistreamer volumio[1305]: info: CoreCommandRouter::volumiosetStartupVolume Oct 03 20:40:57 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 20:40:57 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 20:40:57 hifistreamer volumio[1305]: info: CoreCommandRouter::Close All Modals sent Oct 03 20:40:57 hifistreamer volumio[1305]: info: CoreCommandRouter::Close All Modals sent Oct 03 20:40:57 hifistreamer dhcpcd[1246]: wlan0: carrier acquired Oct 03 20:40:57 hifistreamer wpa_supplicant[1140]: wlan0: Associated with 50:e0:39:3b:25:0d Oct 03 20:40:57 hifistreamer wpa_supplicant[1140]: wlan0: CTRL-EVENT-CONNECTED - Connection to 50:e0:39:3b:25:0d completed [id=0 id_str=] Oct 03 20:40:57 hifistreamer dhcpcd[1246]: wlan0: connected to Access Point `Doofy' Oct 03 20:40:57 hifistreamer wpa_supplicant[1140]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 03 20:40:57 hifistreamer wpa_supplicant[1140]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=NO Oct 03 20:40:57 hifistreamer dhcpcd[1246]: wlan0: IAID 67:62:01:be Oct 03 20:40:57 hifistreamer dhcpcd[1246]: wlan0: adding address fe80::3f72:c202:4872:4ba5 Oct 03 20:40:57 hifistreamer dhcpcd[1246]: ipv6_addaddr1: Permission denied Oct 03 20:40:57 hifistreamer dhcpcd[1246]: wlan0: carrier lost Oct 03 20:40:57 hifistreamer dhcpcd[1246]: wlan0: carrier acquired Oct 03 20:40:57 hifistreamer dhcpcd[1246]: wlan0: IAID 67:62:01:be Oct 03 20:40:58 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:40:58 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:40:58 hifistreamer dhcpcd[1246]: wlan0: soliciting an IPv6 router Oct 03 20:40:58 hifistreamer dhcpcd[1246]: wlan0: rebinding lease of 192.168.1.218 Oct 03 20:40:58 hifistreamer dhcpcd[1246]: wlan0: probing address 192.168.1.218/24 Oct 03 20:40:58 hifistreamer ntpd[1059]: Soliciting pool server 192.36.143.130 Oct 03 20:40:59 hifistreamer volumio[1305]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 03 20:40:59 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 03 20:40:59 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 20:40:59 hifistreamer volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 03 20:40:59 hifistreamer ntpd[1059]: Soliciting pool server 193.150.22.36 Oct 03 20:41:00 hifistreamer ntpd[1059]: Soliciting pool server 185.41.243.43 Oct 03 20:41:00 hifistreamer ntpd[1059]: Soliciting pool server 185.175.56.208 Oct 03 20:41:00 hifistreamer ntpd[1059]: Soliciting pool server 162.159.200.1 Oct 03 20:41:00 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:41:00 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:41:00 hifistreamer volumio[1305]: verbose: New Socket.io Connection to 192.168.1.205 from 192.168.1.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 03 20:41:01 hifistreamer volumio[1305]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 03 20:41:01 hifistreamer ntpd[1059]: Soliciting pool server 185.35.202.223 Oct 03 20:41:01 hifistreamer ntpd[1059]: Soliciting pool server 185.175.56.95 Oct 03 20:41:01 hifistreamer ntpd[1059]: Soliciting pool server 82.148.168.42 Oct 03 20:41:02 hifistreamer volumio[1305]: verbose: New Socket.io Connection to 192.168.1.205 from 192.168.1.183 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 03 20:41:02 hifistreamer ntpd[1059]: Soliciting pool server 62.92.229.27 Oct 03 20:41:02 hifistreamer ntpd[1059]: Soliciting pool server 77.95.79.99 Oct 03 20:41:02 hifistreamer dhcpcd[1246]: wlan0: leased 192.168.1.218 for 7200 seconds Oct 03 20:41:02 hifistreamer avahi-daemon[879]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.218. Oct 03 20:41:02 hifistreamer dhcpcd[1246]: wlan0: adding route to 192.168.1.0/24 Oct 03 20:41:02 hifistreamer avahi-daemon[879]: New relevant interface wlan0.IPv4 for mDNS. Oct 03 20:41:02 hifistreamer dhcpcd[1246]: wlan0: adding default route via 192.168.1.10 Oct 03 20:41:02 hifistreamer avahi-daemon[879]: Registering new address record for 192.168.1.218 on wlan0.IPv4. Oct 03 20:41:03 hifistreamer volumio[1305]: info: MYVOLUMIO: Adding device Oct 03 20:41:03 hifistreamer volumio[1305]: info: MYVOLUMIO: Evaluating Server Oct 03 20:41:03 hifistreamer ntpd[1059]: Soliciting pool server 2a03:94e0:ffff:185:181:61:0:91 Oct 03 20:41:03 hifistreamer ntpd[1059]: Soliciting pool server 91.189.182.32 Oct 03 20:41:03 hifistreamer ntpd[1059]: Soliciting pool server 152.65.32.101 Oct 03 20:41:04 hifistreamer ntpd[1059]: Soliciting pool server 162.159.200.123 Oct 03 20:41:04 hifistreamer ntpd[1059]: Listen normally on 4 wlan0 192.168.1.218:123 Oct 03 20:41:04 hifistreamer ntpd[1059]: new interface(s) found: waking up resolver Oct 03 20:41:05 hifistreamer ntpd[1059]: Soliciting pool server 77.40.226.121 Oct 03 20:41:07 hifistreamer volumio[1305]: info: Discovery: this is already registered, 720afa6a-badd-481c-8a91-deb8b3afafbf Oct 03 20:41:07 hifistreamer volumio[1305]: info: Discovery: Found device HiFiStreamer Oct 03 20:41:07 hifistreamer volumio[1305]: info: CoreCommandRouter::volumioGetState Oct 03 20:41:07 hifistreamer volumio[1305]: info: CorePlayQueue::getTrack 0 Oct 03 20:41:07 hifistreamer volumio[1305]: error: Failed to ping endpoint as1.myvolumio.org : unknown error Oct 03 20:41:07 hifistreamer volumio[1305]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 20:41:07 hifistreamer volumio[1305]: Error: Unable to resolve or reject the same promise twice Oct 03 20:41:07 hifistreamer volumio[1305]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 03 20:41:07 hifistreamer volumio[1305]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Oct 03 20:41:07 hifistreamer volumio[1305]: at Socket.emit (events.js:327:22) Oct 03 20:41:07 hifistreamer volumio[1305]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 03 20:41:07 hifistreamer volumio[1305]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 03 20:41:07 hifistreamer volumio[1305]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 20:41:07 hifistreamer sudo[2177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-03 20:40 Oct 03 20:41:07 hifistreamer sudo[2177]: 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"