-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Sun 2024-04-21 17:48:36 CST. -- Apr 21 17:47:03 volumio volumio[5546]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 21 17:47:03 volumio volumio[5546]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Apr 21 17:47:03 volumio volumio[5546]: info: CoreCommandRouter::volumioGetState Apr 21 17:47:03 volumio volumio[5546]: info: CorePlayQueue::getTrack 0 Apr 21 17:47:08 volumio volumio[5546]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 17:47:08 volumio volumio[5546]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 17:47:08 volumio volumio[5546]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 21 17:47:08 volumio volumio[5546]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 17:47:08 volumio volumio[5546]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 17:47:08 volumio volumio[5546]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 21 17:47:08 volumio volumio[5546]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 21 17:47:08 volumio volumio[5546]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 21 17:47:08 volumio volumio[5546]: info: CoreCommandRouter::volumioGetBrowseSources Apr 21 17:47:08 volumio volumio[5546]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 17:47:08 volumio volumio[5546]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 21 17:47:08 volumio volumio[5546]: info: Completed starting MyVolumio Plugin Apr 21 17:47:08 volumio volumio[5546]: info: BOOT COMPLETED Apr 21 17:47:08 volumio volumio[5546]: [Metrics] CommandRouter: 64s 57.25ms Apr 21 17:47:08 volumio volumio[5546]: info: CoreCommandRouter::volumiosetStartupVolume Apr 21 17:47:08 volumio volumio[5546]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 17:47:08 volumio volumio[5546]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 17:47:08 volumio volumio[5546]: info: CoreCommandRouter::Close All Modals sent Apr 21 17:47:08 volumio volumio[5546]: info: CoreCommandRouter::Close All Modals sent Apr 21 17:47:09 volumio volumio[5546]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 21 17:47:09 volumio volumio[5546]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 17:47:10 volumio volumio[5546]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 21 17:47:14 volumio wpa_supplicant[770]: wlan0: CTRL-EVENT-DISCONNECTED bssid=24:f5:a2:92:78:00 reason=3 locally_generated=1 Apr 21 17:47:14 volumio dhcpcd[782]: wlan0: carrier lost Apr 21 17:47:14 volumio wpa_supplicant[770]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Apr 21 17:47:14 volumio wpa_supplicant[770]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Apr 21 17:47:14 volumio avahi-daemon[535]: Withdrawing address record for 192.168.10.125 on wlan0. Apr 21 17:47:14 volumio avahi-daemon[535]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.10.125. Apr 21 17:47:14 volumio dhcpcd[782]: wlan0: deleting route to 192.168.10.0/24 Apr 21 17:47:14 volumio avahi-daemon[535]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 21 17:47:14 volumio dhcpcd[782]: wlan0: deleting default route via 192.168.10.1 Apr 21 17:47:14 volumio volumio[5546]: info: Discovery: A device disappeared from network Apr 21 17:47:14 volumio volumio[5546]: info: Discovery: Device volumio disappeared from network Apr 21 17:47:14 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1 Apr 21 17:47:14 volumio kernel: xhci_hcd 0000:01:00.0: @000000042a1123d0 00000000 00000000 0e000000 02028000 Apr 21 17:47:15 volumio wpa_supplicant[770]: wlan0: Trying to associate with SSID 'Linksys_RF_5GHz' Apr 21 17:47:15 volumio ntpd[675]: Soliciting pool server 119.28.206.193 Apr 21 17:47:16 volumio wpa_supplicant[770]: wlan0: Associated with 24:f5:a2:92:78:00 Apr 21 17:47:16 volumio wpa_supplicant[770]: wlan0: CTRL-EVENT-CONNECTED - Connection to 24:f5:a2:92:78:00 completed [id=0 id_str=] Apr 21 17:47:16 volumio dhcpcd[782]: wlan0: carrier acquired Apr 21 17:47:16 volumio wpa_supplicant[770]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 21 17:47:16 volumio wpa_supplicant[770]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=SG Apr 21 17:47:16 volumio dhcpcd[782]: wlan0: IAID dd:ab:28:1b Apr 21 17:47:16 volumio dhcpcd[782]: wlan0: soliciting an IPv6 router Apr 21 17:47:16 volumio dhcpcd[782]: wlan0: rebinding lease of 192.168.10.125 Apr 21 17:47:16 volumio dhcpcd[782]: wlan0: probing address 192.168.10.125/24 Apr 21 17:47:17 volumio ntpd[675]: Deleting interface #37 wlan0, 192.168.10.125#123, interface stats: received=23, sent=26, dropped=0, active_time=60 secs Apr 21 17:47:17 volumio ntpd[675]: 108.59.2.24 local addr 192.168.10.125 -> Apr 21 17:47:17 volumio ntpd[675]: 84.16.67.12 local addr 192.168.10.125 -> Apr 21 17:47:17 volumio ntpd[675]: 116.203.151.74 local addr 192.168.10.125 -> Apr 21 17:47:17 volumio ntpd[675]: 193.182.111.14 local addr 192.168.10.125 -> Apr 21 17:47:17 volumio ntpd[675]: 202.112.31.197 local addr 192.168.10.125 -> Apr 21 17:47:17 volumio ntpd[675]: 193.182.111.141 local addr 192.168.10.125 -> Apr 21 17:47:17 volumio ntpd[675]: 162.159.200.123 local addr 192.168.10.125 -> Apr 21 17:47:17 volumio ntpd[675]: 193.182.111.12 local addr 192.168.10.125 -> Apr 21 17:47:21 volumio dhcpcd[782]: wlan0: leased 192.168.10.125 for 86400 seconds Apr 21 17:47:21 volumio avahi-daemon[535]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.10.125. Apr 21 17:47:21 volumio avahi-daemon[535]: New relevant interface wlan0.IPv4 for mDNS. Apr 21 17:47:21 volumio dhcpcd[782]: wlan0: adding route to 192.168.10.0/24 Apr 21 17:47:21 volumio avahi-daemon[535]: Registering new address record for 192.168.10.125 on wlan0.IPv4. Apr 21 17:47:21 volumio dhcpcd[782]: wlan0: adding default route via 192.168.10.1 Apr 21 17:47:21 volumio volumio[5546]: info: Discovery: adding e0a2f6c6-b93c-44e8-b890-ef975e6d055a Apr 21 17:47:21 volumio volumio[5546]: info: Discovery: Found device Volumio Apr 21 17:47:21 volumio volumio[5546]: info: CoreCommandRouter::volumioGetState Apr 21 17:47:21 volumio volumio[5546]: info: CorePlayQueue::getTrack 0 Apr 21 17:47:22 volumio ntpd[675]: Listen normally on 38 wlan0 192.168.10.125:123 Apr 21 17:47:22 volumio ntpd[675]: new interface(s) found: waking up resolver Apr 21 17:47:27 volumio ntpd[675]: Soliciting pool server 119.28.183.184 Apr 21 17:47:29 volumio ntpd[675]: Soliciting pool server 78.46.102.180 Apr 21 17:47:34 volumio ntpd[675]: Soliciting pool server 2606:4700:f1::123 Apr 21 17:47:53 volumio volumio-remote-updater[559]: No test mode Apr 21 17:47:53 volumio volumio-remote-updater[559]: No alpha test mode Apr 21 17:47:53 volumio volumio[5546]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 21 17:47:55 volumio volumio[5546]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 21 17:47:55 volumio volumio[5546]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 21 17:47:58 volumio ntpd[675]: Soliciting pool server 119.28.206.193 Apr 21 17:48:20 volumio wpa_supplicant[770]: wlan0: CTRL-EVENT-DISCONNECTED bssid=24:f5:a2:92:78:00 reason=3 locally_generated=1 Apr 21 17:48:20 volumio dhcpcd[782]: wlan0: carrier lost Apr 21 17:48:20 volumio wpa_supplicant[770]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Apr 21 17:48:20 volumio wpa_supplicant[770]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Apr 21 17:48:20 volumio avahi-daemon[535]: Withdrawing address record for 192.168.10.125 on wlan0. Apr 21 17:48:20 volumio avahi-daemon[535]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.10.125. Apr 21 17:48:20 volumio avahi-daemon[535]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 21 17:48:20 volumio volumio[5546]: info: Discovery: A device disappeared from network Apr 21 17:48:20 volumio volumio[5546]: info: Discovery: Device volumio disappeared from network Apr 21 17:48:20 volumio dhcpcd[782]: wlan0: deleting route to 192.168.10.0/24 Apr 21 17:48:20 volumio dhcpcd[782]: wlan0: deleting default route via 192.168.10.1 Apr 21 17:48:21 volumio wpa_supplicant[770]: wlan0: Trying to associate with SSID 'Linksys_RF_5GHz' Apr 21 17:48:22 volumio wpa_supplicant[770]: wlan0: Associated with 24:f5:a2:92:78:00 Apr 21 17:48:22 volumio wpa_supplicant[770]: wlan0: CTRL-EVENT-CONNECTED - Connection to 24:f5:a2:92:78:00 completed [id=0 id_str=] Apr 21 17:48:22 volumio dhcpcd[782]: wlan0: carrier acquired Apr 21 17:48:22 volumio wpa_supplicant[770]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 21 17:48:22 volumio wpa_supplicant[770]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=SG Apr 21 17:48:22 volumio dhcpcd[782]: wlan0: IAID dd:ab:28:1b Apr 21 17:48:22 volumio ntpd[675]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 21 17:48:23 volumio dhcpcd[782]: wlan0: rebinding lease of 192.168.10.125 Apr 21 17:48:23 volumio dhcpcd[782]: wlan0: probing address 192.168.10.125/24 Apr 21 17:48:23 volumio dhcpcd[782]: wlan0: soliciting an IPv6 router Apr 21 17:48:23 volumio ntpd[675]: Deleting interface #38 wlan0, 192.168.10.125#123, interface stats: received=27, sent=32, dropped=0, active_time=61 secs Apr 21 17:48:23 volumio ntpd[675]: 119.28.206.193 local addr 192.168.10.125 -> Apr 21 17:48:23 volumio ntpd[675]: 78.46.102.180 local addr 192.168.10.125 -> Apr 21 17:48:23 volumio ntpd[675]: 119.28.183.184 local addr 192.168.10.125 -> Apr 21 17:48:23 volumio ntpd[675]: 116.203.151.74 local addr 192.168.10.125 -> Apr 21 17:48:23 volumio ntpd[675]: 193.182.111.14 local addr 192.168.10.125 -> Apr 21 17:48:23 volumio ntpd[675]: 202.112.31.197 local addr 192.168.10.125 -> Apr 21 17:48:23 volumio ntpd[675]: 193.182.111.141 local addr 192.168.10.125 -> Apr 21 17:48:23 volumio ntpd[675]: 162.159.200.123 local addr 192.168.10.125 -> Apr 21 17:48:23 volumio ntpd[675]: 193.182.111.12 local addr 192.168.10.125 -> Apr 21 17:48:23 volumio ntpd[675]: 84.16.67.12 local addr 192.168.10.125 -> Apr 21 17:48:23 volumio ntpd[675]: 108.59.2.24 local addr 192.168.10.125 -> Apr 21 17:48:28 volumio dhcpcd[782]: wlan0: leased 192.168.10.125 for 86400 seconds Apr 21 17:48:28 volumio avahi-daemon[535]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.10.125. Apr 21 17:48:28 volumio avahi-daemon[535]: New relevant interface wlan0.IPv4 for mDNS. Apr 21 17:48:28 volumio dhcpcd[782]: wlan0: adding route to 192.168.10.0/24 Apr 21 17:48:28 volumio avahi-daemon[535]: Registering new address record for 192.168.10.125 on wlan0.IPv4. Apr 21 17:48:28 volumio dhcpcd[782]: wlan0: adding default route via 192.168.10.1 Apr 21 17:48:29 volumio volumio[5546]: info: Discovery: adding e0a2f6c6-b93c-44e8-b890-ef975e6d055a Apr 21 17:48:29 volumio volumio[5546]: info: Discovery: Found device Volumio Apr 21 17:48:29 volumio volumio[5546]: info: CoreCommandRouter::volumioGetState Apr 21 17:48:29 volumio volumio[5546]: info: CorePlayQueue::getTrack 0 Apr 21 17:48:29 volumio ntpd[675]: Listen normally on 39 wlan0 192.168.10.125:123 Apr 21 17:48:29 volumio ntpd[675]: new interface(s) found: waking up resolver Apr 21 17:48:35 volumio volumio[5546]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 17:48:35 volumio volumio[5546]: Error: connect ETIMEDOUT 74.86.228.110:80 Apr 21 17:48:35 volumio volumio[5546]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Apr 21 17:48:35 volumio volumio[5546]: errno: -110, Apr 21 17:48:35 volumio volumio[5546]: code: 'ETIMEDOUT', Apr 21 17:48:35 volumio volumio[5546]: syscall: 'connect', Apr 21 17:48:35 volumio volumio[5546]: address: '74.86.228.110', Apr 21 17:48:35 volumio volumio[5546]: port: 80 Apr 21 17:48:35 volumio volumio[5546]: } Apr 21 17:48:35 volumio volumio[5546]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 17:48:36 volumio sudo[6040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-21 17:47 Apr 21 17:48:36 volumio sudo[6040]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"