-- Logs begin at Mon 2025-09-15 02:51:08 EDT, end at Tue 2025-09-16 07:28:44 EDT. -- Sep 16 07:27:04 pool-pole-speakers-1 volumio[910]: info: [squeezelite_mc] 'client' notification received from homeassistant - LMS HA Addon (10.100.100.50); type is 'disconnect' Sep 16 07:27:04 pool-pole-speakers-1 volumio[910]: info: CoreCommandRouter::volumioGetState Sep 16 07:27:04 pool-pole-speakers-1 volumio[910]: info: CorePlayQueue::getTrack 0 Sep 16 07:27:08 pool-pole-speakers-1 go-librespot[1441]: time="2025-09-16T07:27:08-04:00" level=trace msg="sent dealer ping" Sep 16 07:27:16 pool-pole-speakers-1 go-librespot[1441]: time="2025-09-16T07:27:16-04:00" level=trace msg="received dealer pong" Sep 16 07:27:17 pool-pole-speakers-1 volumio[910]: info: CoreCommandRouter::volumioGetState Sep 16 07:27:17 pool-pole-speakers-1 volumio[910]: info: CorePlayQueue::getTrack 0 Sep 16 07:27:17 pool-pole-speakers-1 volumio[910]: info: Listing playlists Sep 16 07:27:17 pool-pole-speakers-1 volumio[910]: info: Listing playlists Sep 16 07:27:17 pool-pole-speakers-1 volumio[910]: info: CoreCommandRouter::volumioGetState Sep 16 07:27:17 pool-pole-speakers-1 volumio[910]: info: CorePlayQueue::getTrack 0 Sep 16 07:27:18 pool-pole-speakers-1 volumio[910]: info: [squeezelite_mc] 'client' notification received from homeassistant - LMS HA Addon (10.100.100.50); type is 'connect' Sep 16 07:27:18 pool-pole-speakers-1 volumio[910]: info: [squeezelite_mc] Getting players connected to homeassistant - LMS HA Addon (10.100.100.50) Sep 16 07:27:18 pool-pole-speakers-1 volumio[910]: info: [squeezelite_mc] Players connected to homeassistant - LMS HA Addon (10.100.100.50): [{"id":"d8:3a:dd:aa:57:0f","uuid":null,"ip":"10.100.100.52","name":"pool-pole-speakers-2","server":{"ip":"10.100.100.50","name":"homeassistant - LMS HA Addon","ver":"9.0.2","uuid":"b77d2ac5-69eb-4df6-bde3-742738e9849e","jsonPort":"9000","cliPort":"9090"}},{"id":"d8:3a:dd:77:89:86","uuid":null,"ip":"10.100.100.51","name":"pool-pole-speakers-1","server":{"ip":"10.100.100.50","name":"homeassistant - LMS HA Addon","ver":"9.0.2","uuid":"b77d2ac5-69eb-4df6-bde3-742738e9849e","jsonPort":"9000","cliPort":"9090"}},{"id":"d8:3a:dd:83:6d:b3","uuid":null,"ip":"10.100.100.54","name":"pool-house-gazebo-speakers","server":{"ip":"10.100.100.50","name":"homeassistant - LMS HA Addon","ver":"9.0.2","uuid":"b77d2ac5-69eb-4df6-bde3-742738e9849e","jsonPort":"9000","cliPort":"9090"}}] Sep 16 07:27:18 pool-pole-speakers-1 volumio[910]: info: [squeezelite_mc] Player found: {"id":"d8:3a:dd:77:89:86","uuid":null,"ip":"10.100.100.51","name":"pool-pole-speakers-1","server":{"ip":"10.100.100.50","name":"homeassistant - LMS HA Addon","ver":"9.0.2","uuid":"b77d2ac5-69eb-4df6-bde3-742738e9849e","jsonPort":"9000","cliPort":"9090"}} Sep 16 07:27:20 pool-pole-speakers-1 volumio[910]: info: [squeezelite_mc] Squeezelite in sync group with sync master d8:3a:dd:83:6d:b3. Sep 16 07:27:20 pool-pole-speakers-1 volumio[910]: info: CoreCommandRouter::volumioGetState Sep 16 07:27:20 pool-pole-speakers-1 volumio[910]: info: CorePlayQueue::getTrack 0 Sep 16 07:27:27 pool-pole-speakers-1 volumio[910]: info: CoreCommandRouter::volumioGetState Sep 16 07:27:27 pool-pole-speakers-1 volumio[910]: info: CorePlayQueue::getTrack 0 Sep 16 07:27:37 pool-pole-speakers-1 volumio[910]: info: CoreCommandRouter::volumioGetState Sep 16 07:27:37 pool-pole-speakers-1 volumio[910]: info: CorePlayQueue::getTrack 0 Sep 16 07:27:37 pool-pole-speakers-1 volumio[910]: info: Listing playlists Sep 16 07:27:37 pool-pole-speakers-1 volumio[910]: info: Listing playlists Sep 16 07:27:38 pool-pole-speakers-1 go-librespot[1441]: time="2025-09-16T07:27:38-04:00" level=trace msg="sent dealer ping" Sep 16 07:27:38 pool-pole-speakers-1 go-librespot[1441]: time="2025-09-16T07:27:38-04:00" level=trace msg="received dealer pong" Sep 16 07:27:47 pool-pole-speakers-1 volumio[910]: info: CoreCommandRouter::volumioGetState Sep 16 07:27:47 pool-pole-speakers-1 volumio[910]: info: CorePlayQueue::getTrack 0 Sep 16 07:27:57 pool-pole-speakers-1 volumio[910]: info: CoreCommandRouter::volumioGetState Sep 16 07:27:57 pool-pole-speakers-1 volumio[910]: info: CorePlayQueue::getTrack 0 Sep 16 07:27:57 pool-pole-speakers-1 volumio[910]: info: Listing playlists Sep 16 07:27:57 pool-pole-speakers-1 volumio[910]: info: Listing playlists Sep 16 07:28:05 pool-pole-speakers-1 go-librespot[1441]: time="2025-09-16T07:28:05-04:00" level=trace msg="received accesspoint ping" Sep 16 07:28:05 pool-pole-speakers-1 go-librespot[1441]: time="2025-09-16T07:28:05-04:00" level=trace msg="received accesspoint pong ack" Sep 16 07:28:07 pool-pole-speakers-1 volumio[910]: info: CoreCommandRouter::volumioGetState Sep 16 07:28:07 pool-pole-speakers-1 volumio[910]: info: CorePlayQueue::getTrack 0 Sep 16 07:28:08 pool-pole-speakers-1 go-librespot[1441]: time="2025-09-16T07:28:08-04:00" level=trace msg="sent dealer ping" Sep 16 07:28:08 pool-pole-speakers-1 go-librespot[1441]: time="2025-09-16T07:28:08-04:00" level=trace msg="received dealer pong" Sep 16 07:28:17 pool-pole-speakers-1 volumio[910]: info: CoreCommandRouter::volumioGetState Sep 16 07:28:17 pool-pole-speakers-1 volumio[910]: info: CorePlayQueue::getTrack 0 Sep 16 07:28:17 pool-pole-speakers-1 volumio[910]: info: Listing playlists Sep 16 07:28:17 pool-pole-speakers-1 volumio[910]: info: Listing playlists Sep 16 07:28:22 pool-pole-speakers-1 dhcpcd[784]: wlan0: carrier lost Sep 16 07:28:22 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-DISCONNECTED bssid=fa:e2:c6:1a:cd:4d reason=16 Sep 16 07:28:22 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 16 07:28:22 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US Sep 16 07:28:22 pool-pole-speakers-1 dhcpcd[784]: wlan0: deleting route to 10.100.100.0/24 Sep 16 07:28:22 pool-pole-speakers-1 dhcpcd[784]: wlan0: deleting default route via 10.100.100.1 Sep 16 07:28:22 pool-pole-speakers-1 avahi-daemon[501]: Withdrawing address record for 10.100.100.51 on wlan0. Sep 16 07:28:22 pool-pole-speakers-1 avahi-daemon[501]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.100.100.51. Sep 16 07:28:22 pool-pole-speakers-1 avahi-daemon[501]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 16 07:28:22 pool-pole-speakers-1 volumio[910]: info: Discovery: A device disappeared from network Sep 16 07:28:22 pool-pole-speakers-1 volumio[910]: info: Discovery: Device pool-pole-speakers-1 disappeared from network Sep 16 07:28:23 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: Trying to associate with SSID 'BEV-iOT' Sep 16 07:28:23 pool-pole-speakers-1 dhcpcd[784]: wlan0: carrier acquired Sep 16 07:28:23 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: Associated with fa:e2:c6:1a:cd:4d Sep 16 07:28:23 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-CONNECTED - Connection to fa:e2:c6:1a:cd:4d completed [id=0 id_str=] Sep 16 07:28:23 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 16 07:28:23 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Sep 16 07:28:23 pool-pole-speakers-1 dhcpcd[784]: wlan0: IAID dd:77:89:86 Sep 16 07:28:23 pool-pole-speakers-1 dhcpcd[784]: wlan0: carrier lost Sep 16 07:28:23 pool-pole-speakers-1 dhcpcd[784]: wlan0: carrier acquired Sep 16 07:28:23 pool-pole-speakers-1 dhcpcd[784]: wlan0: IAID dd:77:89:86 Sep 16 07:28:23 pool-pole-speakers-1 dhcpcd[784]: wlan0: rebinding lease of 10.100.100.51 Sep 16 07:28:24 pool-pole-speakers-1 dhcpcd[784]: wlan0: soliciting an IPv6 router Sep 16 07:28:25 pool-pole-speakers-1 ntpd[678]: Deleting interface #3 wlan0, 10.100.100.51#123, interface stats: received=1870, sent=1886, dropped=0, active_time=229080 secs Sep 16 07:28:25 pool-pole-speakers-1 ntpd[678]: 149.28.200.179 local addr 10.100.100.51 -> Sep 16 07:28:25 pool-pole-speakers-1 ntpd[678]: 141.11.228.173 local addr 10.100.100.51 -> Sep 16 07:28:25 pool-pole-speakers-1 ntpd[678]: 193.29.63.226 local addr 10.100.100.51 -> Sep 16 07:28:25 pool-pole-speakers-1 ntpd[678]: 69.89.207.99 local addr 10.100.100.51 -> Sep 16 07:28:25 pool-pole-speakers-1 ntpd[678]: 23.142.248.8 local addr 10.100.100.51 -> Sep 16 07:28:25 pool-pole-speakers-1 ntpd[678]: 23.95.49.216 local addr 10.100.100.51 -> Sep 16 07:28:27 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-DISCONNECTED bssid=fa:e2:c6:1a:cd:4d reason=15 Sep 16 07:28:27 pool-pole-speakers-1 dhcpcd[784]: wlan0: carrier lost Sep 16 07:28:27 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 16 07:28:27 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US Sep 16 07:28:27 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: Trying to associate with SSID 'BEV-iOT' Sep 16 07:28:28 pool-pole-speakers-1 dhcpcd[784]: wlan0: carrier acquired Sep 16 07:28:28 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: Associated with fa:e2:c6:1a:cd:4d Sep 16 07:28:28 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-CONNECTED - Connection to fa:e2:c6:1a:cd:4d completed [id=0 id_str=] Sep 16 07:28:28 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 16 07:28:28 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Sep 16 07:28:28 pool-pole-speakers-1 dhcpcd[784]: wlan0: IAID dd:77:89:86 Sep 16 07:28:28 pool-pole-speakers-1 dhcpcd[784]: wlan0: carrier lost Sep 16 07:28:28 pool-pole-speakers-1 dhcpcd[784]: wlan0: carrier acquired Sep 16 07:28:28 pool-pole-speakers-1 dhcpcd[784]: wlan0: IAID dd:77:89:86 Sep 16 07:28:28 pool-pole-speakers-1 dhcpcd[784]: wlan0: soliciting an IPv6 router Sep 16 07:28:29 pool-pole-speakers-1 dhcpcd[784]: wlan0: rebinding lease of 10.100.100.51 Sep 16 07:28:32 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-DISCONNECTED bssid=fa:e2:c6:1a:cd:4d reason=15 Sep 16 07:28:32 pool-pole-speakers-1 dhcpcd[784]: wlan0: carrier lost Sep 16 07:28:32 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 16 07:28:32 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US Sep 16 07:28:32 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: Trying to associate with SSID 'BEV-iOT' Sep 16 07:28:33 pool-pole-speakers-1 dhcpcd[784]: wlan0: carrier acquired Sep 16 07:28:33 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: Associated with fa:e2:c6:1a:cd:4d Sep 16 07:28:33 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-CONNECTED - Connection to fa:e2:c6:1a:cd:4d completed [id=0 id_str=] Sep 16 07:28:33 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 16 07:28:33 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Sep 16 07:28:33 pool-pole-speakers-1 dhcpcd[784]: wlan0: IAID dd:77:89:86 Sep 16 07:28:33 pool-pole-speakers-1 dhcpcd[784]: wlan0: carrier lost Sep 16 07:28:33 pool-pole-speakers-1 dhcpcd[784]: wlan0: carrier acquired Sep 16 07:28:33 pool-pole-speakers-1 dhcpcd[784]: wlan0: IAID dd:77:89:86 Sep 16 07:28:33 pool-pole-speakers-1 dhcpcd[784]: wlan0: soliciting an IPv6 router Sep 16 07:28:33 pool-pole-speakers-1 dhcpcd[784]: wlan0: rebinding lease of 10.100.100.51 Sep 16 07:28:37 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-DISCONNECTED bssid=fa:e2:c6:1a:cd:4d reason=15 Sep 16 07:28:37 pool-pole-speakers-1 dhcpcd[784]: wlan0: carrier lost Sep 16 07:28:37 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 16 07:28:37 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US Sep 16 07:28:37 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: Trying to associate with SSID 'BEV-iOT' Sep 16 07:28:38 pool-pole-speakers-1 dhcpcd[784]: wlan0: carrier acquired Sep 16 07:28:38 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: Associated with fa:e2:c6:1a:cd:4d Sep 16 07:28:38 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-CONNECTED - Connection to fa:e2:c6:1a:cd:4d completed [id=0 id_str=] Sep 16 07:28:38 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 16 07:28:38 pool-pole-speakers-1 wpa_supplicant[768]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Sep 16 07:28:38 pool-pole-speakers-1 dhcpcd[784]: wlan0: IAID dd:77:89:86 Sep 16 07:28:38 pool-pole-speakers-1 dhcpcd[784]: wlan0: carrier lost Sep 16 07:28:38 pool-pole-speakers-1 dhcpcd[784]: wlan0: carrier acquired Sep 16 07:28:38 pool-pole-speakers-1 dhcpcd[784]: wlan0: IAID dd:77:89:86 Sep 16 07:28:38 pool-pole-speakers-1 dhcpcd[784]: wlan0: rebinding lease of 10.100.100.51 Sep 16 07:28:38 pool-pole-speakers-1 dhcpcd[784]: wlan0: probing address 10.100.100.51/24 Sep 16 07:28:38 pool-pole-speakers-1 go-librespot[1441]: time="2025-09-16T07:28:38-04:00" level=trace msg="sent dealer ping" Sep 16 07:28:38 pool-pole-speakers-1 dhcpcd[784]: wlan0: soliciting an IPv6 router Sep 16 07:28:43 pool-pole-speakers-1 volumio[910]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 07:28:43 pool-pole-speakers-1 volumio[910]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 16 07:28:43 pool-pole-speakers-1 volumio[910]: at doSend (dgram.js:692:16) Sep 16 07:28:43 pool-pole-speakers-1 volumio[910]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Sep 16 07:28:43 pool-pole-speakers-1 volumio[910]: at afterDns (dgram.js:638:5) Sep 16 07:28:43 pool-pole-speakers-1 volumio[910]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 16 07:28:43 pool-pole-speakers-1 volumio[910]: errno: -101, Sep 16 07:28:43 pool-pole-speakers-1 volumio[910]: code: 'ENETUNREACH', Sep 16 07:28:43 pool-pole-speakers-1 volumio[910]: syscall: 'send', Sep 16 07:28:43 pool-pole-speakers-1 volumio[910]: address: '255.255.255.255', Sep 16 07:28:43 pool-pole-speakers-1 volumio[910]: port: 3483 Sep 16 07:28:43 pool-pole-speakers-1 volumio[910]: } Sep 16 07:28:43 pool-pole-speakers-1 volumio[910]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 07:28:43 pool-pole-speakers-1 dhcpcd[784]: wlan0: leased 10.100.100.51 for 72655 seconds Sep 16 07:28:43 pool-pole-speakers-1 avahi-daemon[501]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.100.100.51. Sep 16 07:28:43 pool-pole-speakers-1 dhcpcd[784]: wlan0: adding route to 10.100.100.0/24 Sep 16 07:28:43 pool-pole-speakers-1 dhcpcd[784]: wlan0: adding default route via 10.100.100.1 Sep 16 07:28:43 pool-pole-speakers-1 avahi-daemon[501]: New relevant interface wlan0.IPv4 for mDNS. Sep 16 07:28:43 pool-pole-speakers-1 avahi-daemon[501]: Registering new address record for 10.100.100.51 on wlan0.IPv4. Sep 16 07:28:44 pool-pole-speakers-1 sudo[850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-16 07:27 Sep 16 07:28:44 pool-pole-speakers-1 sudo[850]: 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"