-- Logs begin at Tue 2024-08-27 01:47:40 CEST, end at Wed 2024-08-28 10:34:40 CEST. -- Aug 28 10:33:03 volumio kernel: hwmon hwmon1: Voltage normalised Aug 28 10:33:05 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 28 10:33:21 volumio kernel: hwmon hwmon1: Voltage normalised Aug 28 10:33:23 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 28 10:33:38 volumio kernel: hwmon hwmon1: Voltage normalised Aug 28 10:33:40 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 28 10:33:44 volumio kernel: hwmon hwmon1: Voltage normalised Aug 28 10:33:46 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 28 10:33:55 volumio kernel: hwmon hwmon1: Voltage normalised Aug 28 10:33:57 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 28 10:34:01 volumio kernel: hwmon hwmon1: Voltage normalised Aug 28 10:34:03 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 28 10:34:07 volumio wpa_supplicant[950]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="CE" Aug 28 10:34:07 volumio wpa_supplicant[950]: wlan0: Trying to associate with SSID 'CE' Aug 28 10:34:08 volumio dhcpcd[992]: wlan0: carrier acquired Aug 28 10:34:08 volumio wpa_supplicant[950]: wlan0: Associated with 5c:e9:31:43:60:cb Aug 28 10:34:08 volumio wpa_supplicant[950]: wlan0: CTRL-EVENT-CONNECTED - Connection to 5c:e9:31:43:60:cb completed [id=0 id_str=] Aug 28 10:34:08 volumio wpa_supplicant[950]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 28 10:34:08 volumio wpa_supplicant[950]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Aug 28 10:34:08 volumio dhcpcd[992]: wlan0: IAID eb:fb:70:73 Aug 28 10:34:08 volumio dhcpcd[992]: wlan0: carrier lost Aug 28 10:34:08 volumio dhcpcd[992]: wlan0: carrier acquired Aug 28 10:34:08 volumio dhcpcd[992]: wlan0: IAID eb:fb:70:73 Aug 28 10:34:09 volumio dhcpcd[992]: wlan0: soliciting an IPv6 router Aug 28 10:34:09 volumio dhcpcd[992]: wlan0: rebinding lease of 192.168.0.212 Aug 28 10:34:09 volumio dhcpcd[992]: wlan0: probing address 192.168.0.212/24 Aug 28 10:34:15 volumio dhcpcd[992]: wlan0: leased 192.168.0.212 for 7200 seconds Aug 28 10:34:15 volumio avahi-daemon[645]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.212. Aug 28 10:34:15 volumio dhcpcd[992]: wlan0: adding route to 192.168.0.0/24 Aug 28 10:34:15 volumio dhcpcd[992]: wlan0: adding default route via 192.168.0.1 Aug 28 10:34:15 volumio avahi-daemon[645]: New relevant interface wlan0.IPv4 for mDNS. Aug 28 10:34:15 volumio dhcpcd[992]: eth0: deleting route to 192.168.1.0/24 Aug 28 10:34:15 volumio dhcpcd[992]: eth0: deleting default route via 192.168.1.254 Aug 28 10:34:15 volumio dhcpcd[912]: eth0: pid 992 deleted route to 192.168.1.0/24 Aug 28 10:34:15 volumio avahi-daemon[645]: Registering new address record for 192.168.0.212 on wlan0.IPv4. Aug 28 10:34:15 volumio dhcpcd[912]: eth0: pid 992 deleted default route via 192.168.1.254 Aug 28 10:34:15 volumio ntpd[11397]: ntpd exiting on signal 15 (Terminated) Aug 28 10:34:15 volumio ntpd[11397]: 212.83.158.83 local addr 192.168.1.16 -> Aug 28 10:34:15 volumio systemd[1]: Stopping Network Time Service... Aug 28 10:34:15 volumio ntpd[11397]: 212.85.158.10 local addr 192.168.1.16 -> Aug 28 10:34:15 volumio ntpd[11397]: 217.182.137.208 local addr 192.168.1.16 -> Aug 28 10:34:15 volumio systemd[1]: ntp.service: Succeeded. Aug 28 10:34:15 volumio ntpd[11397]: 178.32.23.17 local addr 192.168.1.16 -> Aug 28 10:34:15 volumio ntpd[11397]: 45.13.105.44 local addr 192.168.1.16 -> Aug 28 10:34:15 volumio ntpd[11397]: 51.68.44.27 local addr 192.168.1.16 -> Aug 28 10:34:15 volumio systemd[1]: Stopped Network Time Service. Aug 28 10:34:15 volumio systemd[1]: Starting Network Time Service... Aug 28 10:34:15 volumio ntpd[15802]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 28 10:34:15 volumio ntpd[15802]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Aug 28 10:34:15 volumio systemd[1]: Started Network Time Service. Aug 28 10:34:15 volumio ntpd[15809]: proto: precision = 1.458 usec (-19) Aug 28 10:34:15 volumio ntpd[15809]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 28 10:34:15 volumio ntpd[15809]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 28 10:34:15 volumio ntpd[15809]: Listen and drop on 0 v6wildcard [::]:123 Aug 28 10:34:15 volumio ntpd[15809]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 28 10:34:15 volumio ntpd[15809]: Listen normally on 2 lo 127.0.0.1:123 Aug 28 10:34:15 volumio ntpd[15809]: Listen normally on 3 eth0 192.168.1.16:123 Aug 28 10:34:15 volumio ntpd[15809]: Listen normally on 4 wlan0 192.168.0.212:123 Aug 28 10:34:15 volumio ntpd[15809]: Listening on routing socket on fd #21 for interface updates Aug 28 10:34:15 volumio ntpd[15809]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Aug 28 10:34:15 volumio ntpd[15809]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Aug 28 10:34:17 volumio wpa_supplicant[950]: wlan0: CTRL-EVENT-DISCONNECTED bssid=5c:e9:31:43:60:cb reason=34 Aug 28 10:34:17 volumio dhcpcd[992]: wlan0: carrier lost Aug 28 10:34:17 volumio wpa_supplicant[950]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 28 10:34:17 volumio wpa_supplicant[950]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Aug 28 10:34:17 volumio avahi-daemon[645]: Withdrawing address record for 192.168.0.212 on wlan0. Aug 28 10:34:17 volumio avahi-daemon[645]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.212. Aug 28 10:34:17 volumio avahi-daemon[645]: Interface wlan0.IPv4 no longer relevant for mDNS. Aug 28 10:34:17 volumio dhcpcd[992]: wlan0: deleting route to 192.168.0.0/24 Aug 28 10:34:17 volumio dhcpcd[992]: wlan0: deleting default route via 192.168.0.1 Aug 28 10:34:17 volumio volumio[1091]: info: Discovery: A device disappeared from network Aug 28 10:34:17 volumio ntpd[15809]: ntpd exiting on signal 15 (Terminated) Aug 28 10:34:17 volumio ntpd[15809]: 128.138.140.44 local addr 192.168.0.212 -> Aug 28 10:34:17 volumio systemd[1]: Stopping Network Time Service... Aug 28 10:34:17 volumio systemd[1]: ntp.service: Succeeded. Aug 28 10:34:17 volumio systemd[1]: Stopped Network Time Service. Aug 28 10:34:17 volumio systemd[1]: Starting Network Time Service... Aug 28 10:34:17 volumio ntpd[15862]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 28 10:34:17 volumio ntpd[15862]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Aug 28 10:34:17 volumio systemd[1]: Started Network Time Service. Aug 28 10:34:17 volumio ntpd[15869]: proto: precision = 1.458 usec (-19) Aug 28 10:34:17 volumio ntpd[15869]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 28 10:34:17 volumio ntpd[15869]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 28 10:34:17 volumio ntpd[15869]: Listen and drop on 0 v6wildcard [::]:123 Aug 28 10:34:17 volumio ntpd[15869]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 28 10:34:17 volumio ntpd[15869]: Listen normally on 2 lo 127.0.0.1:123 Aug 28 10:34:17 volumio ntpd[15869]: Listen normally on 3 eth0 192.168.1.16:123 Aug 28 10:34:17 volumio ntpd[15869]: Listening on routing socket on fd #20 for interface updates Aug 28 10:34:17 volumio ntpd[15869]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Aug 28 10:34:17 volumio ntpd[15869]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Aug 28 10:34:18 volumio wpa_supplicant[950]: wlan0: Trying to associate with SSID 'CE' Aug 28 10:34:18 volumio wpa_supplicant[950]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Aug 28 10:34:18 volumio ntpd[15869]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 28 10:34:19 volumio wpa_supplicant[950]: wlan0: Trying to associate with SSID 'CE' Aug 28 10:34:19 volumio ntpd[15869]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 28 10:34:20 volumio dhcpcd[992]: wlan0: carrier acquired Aug 28 10:34:20 volumio wpa_supplicant[950]: wlan0: Associated with 5c:e9:31:43:60:cb Aug 28 10:34:20 volumio wpa_supplicant[950]: wlan0: CTRL-EVENT-CONNECTED - Connection to 5c:e9:31:43:60:cb completed [id=0 id_str=] Aug 28 10:34:20 volumio wpa_supplicant[950]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 28 10:34:20 volumio wpa_supplicant[950]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Aug 28 10:34:20 volumio dhcpcd[992]: wlan0: IAID eb:fb:70:73 Aug 28 10:34:20 volumio dhcpcd[992]: wlan0: carrier lost Aug 28 10:34:20 volumio dhcpcd[992]: wlan0: carrier acquired Aug 28 10:34:20 volumio dhcpcd[992]: wlan0: IAID eb:fb:70:73 Aug 28 10:34:20 volumio dhcpcd[992]: wlan0: rebinding lease of 192.168.0.212 Aug 28 10:34:20 volumio ntpd[15869]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 28 10:34:20 volumio volumio[1091]: info: Discovery: adding 715dab3b-d506-4746-830f-db00ba86e0f3 Aug 28 10:34:20 volumio volumio[1091]: info: Discovery: Found device Volumio Aug 28 10:34:20 volumio volumio[1091]: info: CoreCommandRouter::volumioGetState Aug 28 10:34:21 volumio dhcpcd[992]: wlan0: soliciting an IPv6 router Aug 28 10:34:21 volumio ntpd[15869]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 28 10:34:22 volumio volumio[1091]: (node:1091) UnhandledPromiseRejectionWarning: ConnectionError: Connection error in fetching pairing code Aug 28 10:34:22 volumio volumio[1091]: at PairingCodeRequestService._PairingCodeRequestService_getCodeAndEmit (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/PairingCodeRequestService.js:128:17) Aug 28 10:34:22 volumio volumio[1091]: at runMicrotasks () Aug 28 10:34:22 volumio volumio[1091]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Aug 28 10:34:22 volumio volumio[1091]: (node:1091) 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) Aug 28 10:34:23 volumio wpa_supplicant[950]: wlan0: CTRL-EVENT-DISCONNECTED bssid=5c:e9:31:43:60:cb reason=34 Aug 28 10:34:23 volumio dhcpcd[992]: wlan0: carrier lost Aug 28 10:34:23 volumio wpa_supplicant[950]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 28 10:34:23 volumio wpa_supplicant[950]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Aug 28 10:34:24 volumio wpa_supplicant[950]: wlan0: Trying to associate with SSID 'CE' Aug 28 10:34:24 volumio wpa_supplicant[950]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Aug 28 10:34:26 volumio wpa_supplicant[950]: wlan0: Trying to associate with SSID 'CE' Aug 28 10:34:27 volumio dhcpcd[992]: wlan0: carrier acquired Aug 28 10:34:27 volumio wpa_supplicant[950]: wlan0: Associated with 5c:e9:31:43:60:cb Aug 28 10:34:27 volumio wpa_supplicant[950]: wlan0: CTRL-EVENT-CONNECTED - Connection to 5c:e9:31:43:60:cb completed [id=0 id_str=] Aug 28 10:34:27 volumio wpa_supplicant[950]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 28 10:34:27 volumio wpa_supplicant[950]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Aug 28 10:34:27 volumio dhcpcd[992]: wlan0: IAID eb:fb:70:73 Aug 28 10:34:27 volumio dhcpcd[992]: wlan0: carrier lost Aug 28 10:34:27 volumio dhcpcd[992]: wlan0: carrier acquired Aug 28 10:34:27 volumio dhcpcd[992]: wlan0: IAID eb:fb:70:73 Aug 28 10:34:27 volumio dhcpcd[992]: wlan0: soliciting an IPv6 router Aug 28 10:34:27 volumio dhcpcd[992]: wlan0: rebinding lease of 192.168.0.212 Aug 28 10:34:28 volumio dhcpcd[992]: wlan0: probing address 192.168.0.212/24 Aug 28 10:34:32 volumio dhcpcd[992]: wlan0: leased 192.168.0.212 for 7200 seconds Aug 28 10:34:32 volumio avahi-daemon[645]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.212. Aug 28 10:34:32 volumio avahi-daemon[645]: New relevant interface wlan0.IPv4 for mDNS. Aug 28 10:34:32 volumio avahi-daemon[645]: Registering new address record for 192.168.0.212 on wlan0.IPv4. Aug 28 10:34:32 volumio dhcpcd[992]: wlan0: adding route to 192.168.0.0/24 Aug 28 10:34:32 volumio dhcpcd[992]: wlan0: adding default route via 192.168.0.1 Aug 28 10:34:32 volumio ntpd[15869]: ntpd exiting on signal 15 (Terminated) Aug 28 10:34:32 volumio systemd[1]: Stopping Network Time Service... Aug 28 10:34:32 volumio systemd[1]: ntp.service: Succeeded. Aug 28 10:34:32 volumio systemd[1]: Stopped Network Time Service. Aug 28 10:34:32 volumio systemd[1]: Starting Network Time Service... Aug 28 10:34:33 volumio ntpd[15981]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 28 10:34:33 volumio ntpd[15981]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Aug 28 10:34:33 volumio systemd[1]: Started Network Time Service. Aug 28 10:34:33 volumio ntpd[15988]: proto: precision = 1.406 usec (-19) Aug 28 10:34:33 volumio ntpd[15988]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 28 10:34:33 volumio ntpd[15988]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 28 10:34:33 volumio ntpd[15988]: Listen and drop on 0 v6wildcard [::]:123 Aug 28 10:34:33 volumio ntpd[15988]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 28 10:34:33 volumio ntpd[15988]: Listen normally on 2 lo 127.0.0.1:123 Aug 28 10:34:33 volumio ntpd[15988]: Listen normally on 3 eth0 192.168.1.16:123 Aug 28 10:34:33 volumio ntpd[15988]: Listen normally on 4 wlan0 192.168.0.212:123 Aug 28 10:34:33 volumio ntpd[15988]: Listening on routing socket on fd #21 for interface updates Aug 28 10:34:33 volumio ntpd[15988]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Aug 28 10:34:33 volumio ntpd[15988]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Aug 28 10:34:33 volumio volumio[1091]: info: CoreCommandRouter::volumioGetState Aug 28 10:34:33 volumio volumio[1091]: info: Listing playlists Aug 28 10:34:33 volumio volumio[1091]: info: Listing playlists Aug 28 10:34:33 volumio volumio[1091]: info: Discovery: this is already registered, 715dab3b-d506-4746-830f-db00ba86e0f3 Aug 28 10:34:33 volumio volumio[1091]: info: Discovery: Found device Volumio Aug 28 10:34:33 volumio volumio[1091]: info: CoreCommandRouter::volumioGetState Aug 28 10:34:34 volumio ntpd[15988]: Soliciting pool server 193.52.136.2 Aug 28 10:34:35 volumio ntpd[15988]: Soliciting pool server 51.195.104.188 Aug 28 10:34:35 volumio ntpd[15988]: Soliciting pool server 162.159.200.1 Aug 28 10:34:36 volumio ntpd[15988]: Soliciting pool server 5.39.80.51 Aug 28 10:34:36 volumio ntpd[15988]: Soliciting pool server 82.67.71.253 Aug 28 10:34:36 volumio ntpd[15988]: Soliciting pool server 188.165.201.225 Aug 28 10:34:37 volumio ntpd[15988]: Soliciting pool server 37.59.63.125 Aug 28 10:34:37 volumio ntpd[15988]: Soliciting pool server 82.64.247.11 Aug 28 10:34:37 volumio ntpd[15988]: Soliciting pool server 51.15.182.163 Aug 28 10:34:37 volumio ntpd[15988]: Soliciting pool server 194.57.169.1 Aug 28 10:34:38 volumio ntpd[15988]: Soliciting pool server 146.59.30.179 Aug 28 10:34:38 volumio ntpd[15988]: Soliciting pool server 51.38.81.135 Aug 28 10:34:38 volumio ntpd[15988]: Soliciting pool server 82.64.42.185 Aug 28 10:34:39 volumio ntpd[15988]: Soliciting pool server 82.64.131.93 Aug 28 10:34:39 volumio ntpd[15988]: Soliciting pool server 5.135.153.35 Aug 28 10:34:39 volumio volumio[1091]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 10:34:39 volumio volumio[1091]: Error: bind EADDRINUSE 192.168.0.212:50600 Aug 28 10:34:39 volumio volumio[1091]: at dgram.js:337:20 Aug 28 10:34:39 volumio volumio[1091]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 28 10:34:39 volumio volumio[1091]: errno: -98, Aug 28 10:34:39 volumio volumio[1091]: code: 'EADDRINUSE', Aug 28 10:34:39 volumio volumio[1091]: syscall: 'bind', Aug 28 10:34:39 volumio volumio[1091]: address: '192.168.0.212', Aug 28 10:34:39 volumio volumio[1091]: port: 50600 Aug 28 10:34:39 volumio volumio[1091]: } Aug 28 10:34:39 volumio volumio[1091]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 10:34:40 volumio ntpd[15988]: Soliciting pool server 51.68.44.27 Aug 28 10:34:40 volumio ntpd[15988]: Soliciting pool server 2001:41d0:a:56ae::123 Aug 28 10:34:40 volumio sudo[15999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-28 10:33 Aug 28 10:34:40 volumio sudo[15999]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"