May 25 11:15:25 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:25.524-05:00 level=INFO msg="new address was allocated" component=ble/conn old=7 new=8 May 25 11:15:25 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:25.740-05:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="00:00:00:00:00:00%07 @ 0x1a61aa0" latency=-90.032871ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_SETUP_V1_INTERNET May 25 11:15:25 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:25.884-05:00 level=INFO msg="run WiFi scan" component=server type=REQUEST_TYPE_RUN_WIFI_SCAN peer="00:00:00:00:00:00%07 @ 0x1a61aa0" latency=-474.094075ms timeout=1m0s May 25 11:15:27 forte ntpd[1128]: CLOCK: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 25 11:15:29 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:29.882-05:00 level=INFO msg="emitting wifi scan event" component=server peer="00:00:00:00:00:00%06 @ 0x1801230" networks=38 May 25 11:15:30 forte volumio[1364]: info: Received Get System Info May 25 11:15:30 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 11:15:30 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 11:15:30 forte volumio[1364]: info: Discovery: Getting this device information May 25 11:15:30 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:15:30 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:15:30 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 11:15:30 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 11:15:30 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 11:15:30 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:30.392-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%06 @ 0x1801230" available=true connected=true macAddress=2c:cf:67:18:e2:5f ip4Address=10.254.1.101/16 ip6Address= ssid=resnet-roomdevices May 25 11:15:31 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:31.349-05:00 level=INFO msg="service successfully established" component=discovery/localnet May 25 11:15:33 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:33.192-05:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=6 chunks=5 index=0 tries=11 May 25 11:15:33 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:33.192-05:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%06 @ 0x1801230" event=SERVER_EVENT_TYPE_WIFI_SCAN error="peer is gone" May 25 11:15:33 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:33.192-05:00 level=INFO msg="emitting wifi scan event" component=server peer="00:00:00:00:00:00%07 @ 0x1a61aa0" networks=38 May 25 11:15:36 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:36.930-05:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=6 chunks=1 index=0 tries=11 May 25 11:15:36 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:36.930-05:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%06 @ 0x1801230" event=SERVER_EVENT_TYPE_WIFI_INFO_CHANGED error="peer is gone" May 25 11:15:36 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:36.930-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%07 @ 0x1a61aa0" available=true connected=true macAddress=2c:cf:67:18:e2:5f ip4Address=10.254.1.101/16 ip6Address= ssid=resnet-roomdevices May 25 11:15:44 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:44.669-05:00 level=INFO msg="connect to WiFi" component=server type=REQUEST_TYPE_CONNECT_TO_WIFI peer="00:00:00:00:00:00%07 @ 0x1a61aa0" latency=-522.044003ms timeout=1m0s ssid=OP15MM May 25 11:15:44 forte wpa_supplicant[1325]: wlan0: CTRL-EVENT-DISCONNECTED bssid=28:b3:71:79:c1:5d reason=3 locally_generated=1 May 25 11:15:44 forte dhcpcd[970]: wlan0: carrier lost - roaming May 25 11:15:44 forte dhcpcd[970]: wlan0: changing route to 10.254.0.0/16 May 25 11:15:44 forte dhcpcd[970]: wlan0: changing default route via 10.254.0.1 May 25 11:15:44 forte wpa_supplicant[1325]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all May 25 11:15:44 forte wpa_supplicant[1325]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 25 11:15:44 forte wpa_supplicant[1325]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US May 25 11:15:45 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:45.191-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%07 @ 0x1a61aa0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= May 25 11:15:45 forte volumio[1364]: info: Received Get System Info May 25 11:15:45 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 11:15:45 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 11:15:45 forte volumio[1364]: info: Discovery: Getting this device information May 25 11:15:45 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:15:45 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:15:45 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 11:15:45 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 11:15:45 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 11:15:46 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:46.024-05:00 level=INFO msg="service successfully established" component=discovery/localnet May 25 11:15:46 forte ntpd[1128]: IO: Deleting interface #3 wlan0, 10.254.1.101#123, interface stats: received=192, sent=198, dropped=0, active_time=352 secs May 25 11:15:46 forte ntpd[1128]: PROTO: 144.202.66.214 unlink local addr 10.254.1.101 -> May 25 11:15:46 forte ntpd[1128]: PROTO: 198.137.202.32 unlink local addr 10.254.1.101 -> May 25 11:15:46 forte ntpd[1128]: PROTO: 185.234.20.134 unlink local addr 10.254.1.101 -> May 25 11:15:46 forte ntpd[1128]: PROTO: 23.142.248.9 unlink local addr 10.254.1.101 -> May 25 11:15:46 forte ntpd[1128]: PROTO: 74.208.90.90 unlink local addr 10.254.1.101 -> May 25 11:15:46 forte ntpd[1128]: PROTO: 69.10.208.170 unlink local addr 10.254.1.101 -> May 25 11:15:46 forte ntpd[1128]: PROTO: 158.51.99.19 unlink local addr 10.254.1.101 -> May 25 11:15:46 forte ntpd[1128]: PROTO: 142.248.192.33 unlink local addr 10.254.1.101 -> May 25 11:15:46 forte ntpd[1128]: PROTO: 209.177.158.85 unlink local addr 10.254.1.101 -> May 25 11:15:46 forte ntpd[1128]: PROTO: 23.186.168.127 unlink local addr 10.254.1.101 -> May 25 11:15:46 forte ntpd[1128]: PROTO: 23.186.168.125 unlink local addr 10.254.1.101 -> May 25 11:15:46 forte ntpd[1128]: PROTO: 5.78.139.245 unlink local addr 10.254.1.101 -> May 25 11:15:46 forte ntpd[1128]: PROTO: 173.198.243.126 unlink local addr 10.254.1.101 -> May 25 11:15:46 forte ntpd[1128]: PROTO: 162.244.81.139 unlink local addr 10.254.1.101 -> May 25 11:15:46 forte ntpd[1128]: PROTO: 144.202.41.38 unlink local addr 10.254.1.101 -> May 25 11:15:46 forte ntpd[1128]: PROTO: 45.77.126.122 unlink local addr 10.254.1.101 -> May 25 11:15:47 forte wpa_supplicant[1325]: wlan0: Trying to associate with a6:bc:e9:dd:17:b1 (SSID='OP15MM' freq=2437 MHz) May 25 11:15:47 forte wpa_supplicant[1325]: wlan0: Associated with a6:bc:e9:dd:17:b1 May 25 11:15:47 forte wpa_supplicant[1325]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 25 11:15:47 forte wpa_supplicant[1325]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US May 25 11:15:47 forte wpa_supplicant[1325]: wlan0: WPA: Key negotiation completed with a6:bc:e9:dd:17:b1 [PTK=CCMP GTK=CCMP] May 25 11:15:47 forte wpa_supplicant[1325]: wlan0: CTRL-EVENT-CONNECTED - Connection to a6:bc:e9:dd:17:b1 completed [id=2 id_str=] May 25 11:15:47 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:47.786-05:00 level=INFO msg="connected temporarily to WiFi network" component=volumio ssid=OP15MM May 25 11:15:47 forte dhcpcd[970]: wlan0: carrier acquired May 25 11:15:47 forte dhcpcd[970]: wlan0: connected to Access Point: OP15MM May 25 11:15:47 forte avahi-daemon[1741]: Withdrawing address record for 10.254.1.101 on wlan0. May 25 11:15:47 forte avahi-daemon[1741]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.254.1.101. May 25 11:15:47 forte wpa_supplicant[1325]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a6:bc:e9:dd:17:b1 reason=3 locally_generated=1 May 25 11:15:47 forte dhcpcd[970]: wlan0: deleting route to 10.254.0.0/16 May 25 11:15:47 forte avahi-daemon[1741]: Interface wlan0.IPv4 no longer relevant for mDNS. May 25 11:15:47 forte dhcpcd[970]: wlan0: deleting default route via 10.254.0.1 May 25 11:15:47 forte volumio[1364]: info: Discovery: A device disappeared from network May 25 11:15:47 forte wpa_supplicant[1325]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all May 25 11:15:47 forte wpa_supplicant[1325]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 25 11:15:47 forte systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 25 11:15:47 forte systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 25 11:15:47 forte systemd[1]: welcome.service: Deactivated successfully. May 25 11:15:47 forte systemd[1]: Stopped welcome.service - Show a welcome message on console. May 25 11:15:47 forte systemd[1]: Stopping welcome.service - Show a welcome message on console... May 25 11:15:47 forte wpa_supplicant[1325]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US May 25 11:15:47 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings May 25 11:15:47 forte volumio[1364]: info: Saving new wireless network May 25 11:15:47 forte dhcpcd[970]: wlan0: IAID 67:18:e2:5f May 25 11:15:47 forte dhcpcd[970]: wlan0: carrier lost - roaming May 25 11:15:47 forte dhcpcd[970]: wlan0: carrier lost - roaming May 25 11:15:47 forte systemd[1]: Starting welcome.service - Show a welcome message on console... May 25 11:15:47 forte welcome[2535]: Resolved ip:[0] May 25 11:15:47 forte sudo[2557]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf May 25 11:15:47 forte sudo[2557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:15:47 forte systemd[1]: Finished welcome.service - Show a welcome message on console. May 25 11:15:47 forte systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 25 11:15:47 forte sudo[2557]: pam_unix(sudo:session): session closed for user root May 25 11:15:47 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 25 11:15:47 forte sudo[2563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 25 11:15:47 forte sudo[2563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:15:47 forte systemd[1]: Stopping wireless.service - Wireless Services... May 25 11:15:47 forte systemd[1]: wireless.service: Killing process 1325 (wpa_supplicant) with signal SIGKILL. May 25 11:15:47 forte systemd[1]: wireless.service: Deactivated successfully. May 25 11:15:47 forte systemd[1]: Stopped wireless.service - Wireless Services. May 25 11:15:47 forte systemd[1]: wireless.service: Consumed 1.666s CPU time. May 25 11:15:47 forte systemd[1]: Starting wireless.service - Wireless Services... May 25 11:15:48 forte wireless.js[2570]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless May 25 11:15:48 forte wireless.js[2570]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow May 25 11:15:48 forte sudo[2594]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 25 11:15:48 forte sudo[2594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 11:15:48 forte sudo[2594]: pam_unix(sudo:session): session closed for user root May 25 11:15:48 forte sudo[2596]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 25 11:15:48 forte sudo[2596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 11:15:48 forte dhcpcd[970]: wlan0: soliciting an IPv6 router May 25 11:15:48 forte dhcpcd[970]: wlan0: carrier lost May 25 11:15:48 forte sudo[2596]: pam_unix(sudo:session): session closed for user root May 25 11:15:48 forte volumio[1364]: info: Received Get System Info May 25 11:15:48 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 11:15:48 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 11:15:48 forte volumio[1364]: info: Discovery: Getting this device information May 25 11:15:48 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:15:48 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:15:48 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 11:15:48 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:48.733-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%07 @ 0x1a61aa0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= May 25 11:15:48 forte wireless.js[2570]: WIRELESS.JS - INFO: Cleaning previous... May 25 11:15:48 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 11:15:48 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 11:15:48 forte sudo[2606]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 25 11:15:48 forte sudo[2606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 11:15:48 forte sudo[2606]: pam_unix(sudo:session): session closed for user root May 25 11:15:48 forte kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 25 11:15:48 forte wireless.js[2570]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations May 25 11:15:48 forte wireless.js[2570]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 1ms May 25 11:15:48 forte wireless.js[2570]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: 2c:cf:67:18:e2:5f) May 25 11:15:48 forte sudo[2613]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 25 11:15:48 forte sudo[2613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:15:48 forte sudo[2613]: pam_unix(sudo:session): session closed for user root May 25 11:15:48 forte sudo[2621]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 25 11:15:48 forte sudo[2621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:15:48 forte sudo[2626]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:15:48 forte sudo[2626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:15:48 forte sudo[2626]: pam_unix(sudo:session): session closed for user root May 25 11:15:49 forte volumio[1364]: info: Received Get System Info May 25 11:15:49 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 11:15:49 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 11:15:49 forte volumio[1364]: info: Discovery: Getting this device information May 25 11:15:49 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:15:49 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:15:49 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 11:15:49 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:49.253-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%07 @ 0x1a61aa0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= May 25 11:15:49 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 11:15:49 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 11:15:49 forte sudo[2630]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:15:49 forte sudo[2630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:15:49 forte sudo[2630]: pam_unix(sudo:session): session closed for user root May 25 11:15:50 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:50.568-05:00 level=INFO msg="service successfully established" component=discovery/localnet May 25 11:15:50 forte sudo[2633]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:15:50 forte sudo[2633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:15:50 forte sudo[2633]: pam_unix(sudo:session): session closed for user root May 25 11:15:51 forte sudo[2621]: pam_unix(sudo:session): session closed for user root May 25 11:15:51 forte wireless.js[2570]: WIRELESS.JS - INFO: Regdomain already correct: US May 25 11:15:51 forte wireless.js[2570]: WIRELESS.JS - INFO: Start wireless flow May 25 11:15:51 forte wireless.js[2570]: WIRELESS.JS - INFO: Stopped hotspot (if there).. May 25 11:15:51 forte sudo[2639]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 25 11:15:51 forte sudo[2639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 11:15:51 forte sudo[2639]: pam_unix(sudo:session): session closed for user root May 25 11:15:51 forte sudo[2641]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 25 11:15:51 forte sudo[2641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 11:15:52 forte sudo[2641]: pam_unix(sudo:session): session closed for user root May 25 11:15:52 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:52.157-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%07 @ 0x1a61aa0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= May 25 11:15:52 forte volumio[1364]: info: Received Get System Info May 25 11:15:52 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 11:15:52 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 11:15:52 forte volumio[1364]: info: Discovery: Getting this device information May 25 11:15:52 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:15:52 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:15:52 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 11:15:52 forte sudo[2648]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:15:52 forte sudo[2648]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:15:52 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 11:15:52 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 11:15:52 forte sudo[2648]: pam_unix(sudo:session): session closed for user root May 25 11:15:52 forte volumio[1364]: info: Volumio Network Manager: Network status updated: 0 May 25 11:15:52 forte wireless.js[2570]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations May 25 11:15:52 forte wireless.js[2570]: WIRELESS.JS - INFO: STAGE 1: wlan0 validated and ready (MAC: 2c:cf:67:18:e2:5f, USB: false) May 25 11:15:52 forte wpa_supplicant[2664]: Successfully initialized wpa_supplicant May 25 11:15:52 forte kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 25 11:15:52 forte wpa_supplicant[2664]: nl80211: kernel reports: Registration to specific type not supported May 25 11:15:52 forte sudo[2670]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 25 11:15:52 forte sudo[2670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 11:15:52 forte sudo[2670]: pam_unix(sudo:session): session closed for user root May 25 11:15:52 forte volumio[1364]: info: Received Get System Info May 25 11:15:52 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 11:15:52 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 11:15:52 forte volumio[1364]: info: Discovery: Getting this device information May 25 11:15:52 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:15:52 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:15:52 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 11:15:52 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:52.691-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%07 @ 0x1a61aa0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= May 25 11:15:52 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 11:15:52 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 11:15:52 forte sudo[2678]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:15:52 forte sudo[2678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:15:52 forte sudo[2678]: pam_unix(sudo:session): session closed for user root May 25 11:15:53 forte wireless.js[2570]: WIRELESS.JS - INFO: DHCP IP fallback May 25 11:15:53 forte wireless.js[2570]: WIRELESS.JS - INFO: STAGE 2: Starting event-driven WPA state monitor May 25 11:15:53 forte wireless.js[2570]: WIRELESS.JS - INFO: WpaStateMachine: Starting state monitor for wlan0 May 25 11:15:53 forte wireless.js[2570]: WIRELESS.JS - INFO: WpaStateMachine: State transition: NULL -> SCANNING (duration: 0ms) May 25 11:15:53 forte sudo[2689]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:15:53 forte sudo[2689]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:15:53 forte sudo[2689]: pam_unix(sudo:session): session closed for user root May 25 11:15:53 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:53.993-05:00 level=INFO msg="service successfully established" component=discovery/localnet May 25 11:15:54 forte sudo[2698]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:15:54 forte sudo[2698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:15:54 forte sudo[2698]: pam_unix(sudo:session): session closed for user root May 25 11:15:55 forte wpa_supplicant[2667]: wlan0: Trying to associate with a6:bc:e9:dd:17:b1 (SSID='OP15MM' freq=2437 MHz) May 25 11:15:55 forte wpa_supplicant[2667]: wlan0: Associated with a6:bc:e9:dd:17:b1 May 25 11:15:55 forte wpa_supplicant[2667]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 25 11:15:55 forte wpa_supplicant[2667]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US May 25 11:15:55 forte wpa_supplicant[2667]: wlan0: WPA: Key negotiation completed with a6:bc:e9:dd:17:b1 [PTK=CCMP GTK=CCMP] May 25 11:15:55 forte wpa_supplicant[2667]: wlan0: CTRL-EVENT-CONNECTED - Connection to a6:bc:e9:dd:17:b1 completed [id=0 id_str=] May 25 11:15:55 forte dhcpcd[970]: wlan0: carrier acquired May 25 11:15:55 forte dhcpcd[970]: wlan0: IAID 67:18:e2:5f May 25 11:15:55 forte wireless.js[2570]: WIRELESS.JS - INFO: WpaStateMachine: State transition: SCANNING -> COMPLETED (duration: 1511ms) May 25 11:15:55 forte wireless.js[2570]: WIRELESS.JS - INFO: WpaStateMachine: COMPLETED - connection successful May 25 11:15:55 forte wireless.js[2570]: WIRELESS.JS - INFO: STAGE 2: Connection successful - Connected to a6:bc:e9:dd:17:b1 May 25 11:15:55 forte wireless.js[2570]: WIRELESS.JS - INFO: Onboard WiFi adapter detected, using standard dhcpcd flow May 25 11:15:55 forte volumio[1364]: info: Received Get System Info May 25 11:15:55 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 11:15:55 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 11:15:55 forte volumio[1364]: info: Discovery: Getting this device information May 25 11:15:55 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:15:55 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:15:55 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 11:15:55 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:55.768-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%07 @ 0x1a61aa0" available=true connected=true macAddress=2c:cf:67:18:e2:5f ip4Address= ip6Address= ssid=OP15MM May 25 11:15:55 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 11:15:55 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 11:15:55 forte sudo[2709]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:15:55 forte sudo[2709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:15:55 forte sudo[2709]: pam_unix(sudo:session): session closed for user root May 25 11:15:56 forte dhcpcd[970]: wlan0: soliciting an IPv6 router May 25 11:15:56 forte sudo[2711]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd wlan0 May 25 11:15:56 forte sudo[2711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 11:15:56 forte sudo[2711]: pam_unix(sudo:session): session closed for user root May 25 11:15:56 forte dhcpcd[970]: control_free: No such file or directory May 25 11:15:56 forte dhcpcd[970]: ps_ctl_dispatch: cannot handle another client May 25 11:15:56 forte volumio5-onboarding[1814]: time=2026-05-25T11:15:56.600-05:00 level=INFO msg="service successfully established" component=discovery/localnet May 25 11:15:56 forte sudo[2714]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:15:56 forte sudo[2714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:15:56 forte sudo[2714]: pam_unix(sudo:session): session closed for user root May 25 11:15:57 forte dhcpcd[970]: wlan0: soliciting a DHCP lease May 25 11:15:57 forte dhcpcd[970]: wlan0: probing address 10.216.62.187/24 May 25 11:15:57 forte volumio[1364]: info: Discovery: Networking Restart detected, restarting advertisement and browsing May 25 11:15:57 forte volumio[1364]: info: Discovery: Restarting Advertising May 25 11:15:57 forte volumio[1364]: info: Discovery: Stopping existing advertisement May 25 11:15:57 forte volumio[1364]: info: Discovery: Restarting Browsing May 25 11:15:57 forte sudo[2719]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:15:57 forte sudo[2719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:15:57 forte sudo[2719]: pam_unix(sudo:session): session closed for user root May 25 11:15:58 forte wireless.js[2570]: WIRELESS.JS - INFO: Start ap May 25 11:15:58 forte wireless.js[2570]: WIRELESS.JS - INFO: Notified systemd about wireless ready May 25 11:15:58 forte kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 25 11:15:58 forte systemd[1]: Started wireless.service - Wireless Services. May 25 11:15:58 forte sudo[2563]: pam_unix(sudo:session): session closed for user root May 25 11:15:58 forte volumio[1364]: info: Discovery: A device disappeared from network May 25 11:15:58 forte sudo[2728]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:15:58 forte sudo[2728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:15:58 forte sudo[2728]: pam_unix(sudo:session): session closed for user root May 25 11:15:59 forte wireless.js[2570]: WIRELESS.JS - INFO: trying... May 25 11:15:59 forte sudo[2739]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 25 11:15:59 forte sudo[2739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:15:59 forte sudo[2739]: pam_unix(sudo:session): session closed for user root May 25 11:15:59 forte sudo[2744]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:15:59 forte sudo[2744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 11:15:59 forte wireless.js[2570]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined May 25 11:15:59 forte sudo[2744]: pam_unix(sudo:session): session closed for user root May 25 11:15:59 forte sudo[2747]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:15:59 forte sudo[2747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:15:59 forte sudo[2747]: pam_unix(sudo:session): session closed for user root May 25 11:16:00 forte wireless.js[2570]: WIRELESS.JS - INFO: trying... May 25 11:16:00 forte sudo[2758]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 25 11:16:00 forte sudo[2758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:00 forte sudo[2758]: pam_unix(sudo:session): session closed for user root May 25 11:16:00 forte sudo[2761]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:16:00 forte sudo[2761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 11:16:00 forte sudo[2761]: pam_unix(sudo:session): session closed for user root May 25 11:16:00 forte wireless.js[2570]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined May 25 11:16:00 forte sudo[2764]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:16:00 forte sudo[2764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:00 forte sudo[2764]: pam_unix(sudo:session): session closed for user root May 25 11:16:01 forte wireless.js[2570]: WIRELESS.JS - INFO: WARNING: dhcpcd running but no IP assigned yet May 25 11:16:01 forte wireless.js[2570]: WIRELESS.JS - INFO: trying... May 25 11:16:01 forte sudo[2780]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 25 11:16:01 forte sudo[2780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:01 forte sudo[2780]: pam_unix(sudo:session): session closed for user root May 25 11:16:01 forte sudo[2783]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:16:01 forte sudo[2783]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 11:16:01 forte sudo[2783]: pam_unix(sudo:session): session closed for user root May 25 11:16:01 forte wireless.js[2570]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined May 25 11:16:01 forte sudo[2786]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:16:01 forte sudo[2786]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:01 forte sudo[2786]: pam_unix(sudo:session): session closed for user root May 25 11:16:02 forte wireless.js[2570]: WIRELESS.JS - INFO: trying... May 25 11:16:02 forte sudo[2811]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 25 11:16:02 forte sudo[2811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:02 forte sudo[2811]: pam_unix(sudo:session): session closed for user root May 25 11:16:02 forte sudo[2815]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:16:02 forte sudo[2815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 11:16:02 forte sudo[2815]: pam_unix(sudo:session): session closed for user root May 25 11:16:02 forte wireless.js[2570]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined May 25 11:16:02 forte dhcpcd[970]: wlan0: leased 10.216.62.187 for 3599 seconds May 25 11:16:02 forte avahi-daemon[1741]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.216.62.187. May 25 11:16:02 forte avahi-daemon[1741]: New relevant interface wlan0.IPv4 for mDNS. May 25 11:16:02 forte avahi-daemon[1741]: Registering new address record for 10.216.62.187 on wlan0.IPv4. May 25 11:16:02 forte dhcpcd[970]: wlan0: adding route to 10.216.62.0/24 May 25 11:16:02 forte dhcpcd[970]: wlan0: adding default route via 10.216.62.122 May 25 11:16:02 forte systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 25 11:16:02 forte systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 25 11:16:02 forte systemd[1]: welcome.service: Deactivated successfully. May 25 11:16:02 forte systemd[1]: Stopped welcome.service - Show a welcome message on console. May 25 11:16:02 forte systemd[1]: Stopping welcome.service - Show a welcome message on console... May 25 11:16:02 forte volumio[1364]: info: Discovery: Started advertising with name: Forte May 25 11:16:02 forte systemd[1]: Starting welcome.service - Show a welcome message on console... May 25 11:16:02 forte welcome[2831]: Resolved ip:[1] 10.216.62.187 May 25 11:16:02 forte systemd[1]: Finished welcome.service - Show a welcome message on console. May 25 11:16:02 forte systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 25 11:16:02 forte sudo[2843]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:16:02 forte sudo[2843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:02 forte sudo[2843]: pam_unix(sudo:session): session closed for user root May 25 11:16:02 forte sudo[2848]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 25 11:16:02 forte sudo[2848]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:02 forte sudo[2848]: pam_unix(sudo:session): session closed for user root May 25 11:16:02 forte volumio5-onboarding[1814]: time=2026-05-25T11:16:02.890-05:00 level=INFO msg="emitting wifi scan event" component=server peer="00:00:00:00:00:00%07 @ 0x1a61aa0" networks=60 May 25 11:16:03 forte volumio[1364]: info: Received Get System Info May 25 11:16:03 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 11:16:03 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 11:16:03 forte volumio[1364]: info: Discovery: Getting this device information May 25 11:16:03 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:16:03 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:16:03 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 11:16:03 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 11:16:03 forte volumio5-onboarding[1814]: time=2026-05-25T11:16:03.323-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%07 @ 0x1a61aa0" available=true connected=true macAddress=2c:cf:67:18:e2:5f ip4Address=10.216.62.187/24 ip6Address= ssid=OP15MM May 25 11:16:03 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 11:16:03 forte wireless.js[2570]: WIRELESS.JS - INFO: trying... May 25 11:16:03 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection May 25 11:16:03 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo May 25 11:16:03 forte sudo[2864]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 25 11:16:03 forte sudo[2864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:03 forte volumio5-onboarding[1814]: time=2026-05-25T11:16:03.389-05:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=10.216.62.122:38250 May 25 11:16:03 forte sudo[2864]: pam_unix(sudo:session): session closed for user root May 25 11:16:03 forte sudo[2867]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:16:03 forte sudo[2867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:03 forte sudo[2867]: pam_unix(sudo:session): session closed for user root May 25 11:16:03 forte sudo[2869]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:16:03 forte sudo[2869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 11:16:03 forte sudo[2869]: pam_unix(sudo:session): session closed for user root May 25 11:16:03 forte wireless.js[2570]: WIRELESS.JS - INFO: ... wlan0 IPv4 is 10.216.62.187, ipV6 is undefined May 25 11:16:03 forte wireless.js[2570]: WIRELESS.JS - INFO: Connected to SSID: OP15MM May 25 11:16:03 forte wireless.js[2570]: WIRELESS.JS - INFO: It's done! AP May 25 11:16:03 forte wireless.js[2570]: WIRELESS.JS - INFO: Restarting avahi-daemon... May 25 11:16:03 forte sudo[2875]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 25 11:16:03 forte sudo[2875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:03 forte sudo[2881]: root : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart avahi-daemon May 25 11:16:03 forte sudo[2881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 11:16:03 forte sudo[2875]: pam_unix(sudo:session): session closed for user root May 25 11:16:03 forte systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 11:16:03 forte systemd[1]: shairport-sync.service: Deactivated successfully. May 25 11:16:03 forte systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 11:16:03 forte systemd[1]: shairport-sync.service: Consumed 1.801s CPU time. May 25 11:16:03 forte systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... May 25 11:16:03 forte avahi-daemon[1741]: Got SIGTERM, quitting. May 25 11:16:03 forte avahi-daemon[1741]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.216.62.187. May 25 11:16:03 forte volumio5-onboarding[1814]: time=2026-05-25T11:16:03.436-05:00 level=WARN msg="disconnected from Avahi daemon, trying to reconnect" component=discovery/localnet error="avahi: Daemon connection failed" May 25 11:16:03 forte avahi-daemon[1741]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. May 25 11:16:03 forte volumio[1364]: info: Discovery: Browse raised the following error Error: dns service error: unknown May 25 11:16:03 forte volumio[1364]: info: Discovery: Restarting Browsing May 25 11:16:03 forte volumio[1364]: info: Discovery: Browse raised the following error Error: dns service error: unknown May 25 11:16:03 forte volumio[1364]: info: Discovery: Restarting Browsing May 25 11:16:03 forte volumio[1364]: info: Discovery: Restart already pending, ignoring duplicate call May 25 11:16:03 forte volumio[1364]: info: Discovery: Browse raised the following error Error: dns service error: unknown May 25 11:16:03 forte volumio[1364]: info: Discovery: Restarting Browsing May 25 11:16:03 forte volumio[1364]: info: Discovery: Restart already pending, ignoring duplicate call May 25 11:16:03 forte volumio[1364]: info: Discovery: Browse raised the following error Error: dns service error: unknown May 25 11:16:03 forte volumio[1364]: info: Discovery: Restarting Browsing May 25 11:16:03 forte volumio[1364]: info: Discovery: Restart already pending, ignoring duplicate call May 25 11:16:03 forte volumio[1364]: error: Discovery: Advertisement error: Error: dns service error: unknown May 25 11:16:03 forte volumio[1364]: error: Discovery: advertisement error: Error: dns service error: unknown May 25 11:16:03 forte volumio[1364]: info: Discovery: Advertisement raised the following error Error: dns service error: unknown May 25 11:16:03 forte volumio[1364]: info: Discovery: Stopping Advertising Immediately May 25 11:16:03 forte volumio[1364]: info: Discovery: Stopping existing advertisement May 25 11:16:03 forte dbus-daemon[931]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.32' (uid=0 pid=1703 comm="/usr/sbin/smbd --foreground --no-process-group") May 25 11:16:03 forte avahi-daemon[1741]: avahi-daemon 0.8 exiting. May 25 11:16:03 forte systemd[1]: avahi-daemon.service: Deactivated successfully. May 25 11:16:03 forte systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. May 25 11:16:03 forte systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... May 25 11:16:03 forte avahi-daemon[2884]: Process 1741 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) May 25 11:16:03 forte avahi-daemon[2884]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). May 25 11:16:03 forte avahi-daemon[2884]: Successfully dropped root privileges. May 25 11:16:03 forte avahi-daemon[2884]: avahi-daemon 0.8 starting up. May 25 11:16:03 forte dbus-daemon[931]: [system] Successfully activated service 'org.freedesktop.Avahi' May 25 11:16:03 forte systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. May 25 11:16:03 forte avahi-daemon[2884]: Successfully called chroot(). May 25 11:16:03 forte avahi-daemon[2884]: Successfully dropped remaining capabilities. May 25 11:16:03 forte avahi-daemon[2884]: Loading service file /services/volumio.service. May 25 11:16:03 forte avahi-daemon[2884]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.216.62.187. May 25 11:16:03 forte sudo[2881]: pam_unix(sudo:session): session closed for user root May 25 11:16:03 forte avahi-daemon[2884]: New relevant interface wlan0.IPv4 for mDNS. May 25 11:16:03 forte avahi-daemon[2884]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. May 25 11:16:03 forte avahi-daemon[2884]: New relevant interface lo.IPv4 for mDNS. May 25 11:16:03 forte avahi-daemon[2884]: Network interface enumeration completed. May 25 11:16:03 forte avahi-daemon[2884]: Registering new address record for 10.216.62.187 on wlan0.IPv4. May 25 11:16:03 forte avahi-daemon[2884]: Registering new address record for 127.0.0.1 on lo.IPv4. May 25 11:16:03 forte systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 11:16:03 forte wireless.js[2570]: WIRELESS.JS - INFO: Notified systemd about wireless ready May 25 11:16:04 forte ntpd[1128]: IO: Listen normally on 4 wlan0 10.216.62.187:123 May 25 11:16:04 forte ntpd[1128]: IO: new interface(s) found: waking up resolver May 25 11:16:04 forte avahi-daemon[2884]: Server startup complete. Host name is forte.local. Local service cookie is 4225813477. May 25 11:16:05 forte avahi-daemon[2884]: Service "Forte" (/services/volumio.service) successfully established. May 25 11:16:05 forte volumio5-onboarding[1814]: time=2026-05-25T11:16:05.291-05:00 level=INFO msg="service successfully established" component=discovery/localnet May 25 11:16:05 forte volumio5-onboarding[1814]: time=2026-05-25T11:16:05.330-05:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%07,10.216.62.122:38250 @ 0x1a61aa0" latency=-537.293042ms timeout=10s May 25 11:16:05 forte volumio5-onboarding[1814]: time=2026-05-25T11:16:05.525-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%07,10.216.62.122:38250 @ 0x1a61aa0" latency=-537.293042ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=193.695737ms May 25 11:16:05 forte volumio5-onboarding[1814]: time=2026-05-25T11:16:05.558-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%07,10.216.62.122:38250 @ 0x1a61aa0" latency=-537.293042ms timeout=10s endpoint=https://securetoken.googleapis.com duration=227.033198ms May 25 11:16:05 forte volumio5-onboarding[1814]: time=2026-05-25T11:16:05.646-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%07,10.216.62.122:38250 @ 0x1a61aa0" latency=-537.293042ms timeout=10s endpoint=http://plugins.volumio.org duration=315.466281ms May 25 11:16:05 forte volumio5-onboarding[1814]: time=2026-05-25T11:16:05.646-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%07,10.216.62.122:38250 @ 0x1a61aa0" latency=-537.293042ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=314.85768ms May 25 11:16:05 forte volumio5-onboarding[1814]: time=2026-05-25T11:16:05.662-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%07,10.216.62.122:38250 @ 0x1a61aa0" latency=-537.293042ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=329.762458ms May 25 11:16:05 forte volumio5-onboarding[1814]: time=2026-05-25T11:16:05.662-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%07,10.216.62.122:38250 @ 0x1a61aa0" latency=-537.293042ms timeout=10s endpoint=https://functions.volumio.cloud duration=329.905103ms May 25 11:16:05 forte volumio5-onboarding[1814]: time=2026-05-25T11:16:05.662-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%07,10.216.62.122:38250 @ 0x1a61aa0" latency=-537.293042ms timeout=10s endpoint=https://functions.volumio.cloud duration=331.239205ms May 25 11:16:05 forte volumio5-onboarding[1814]: time=2026-05-25T11:16:05.704-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%07,10.216.62.122:38250 @ 0x1a61aa0" latency=-537.293042ms timeout=10s endpoint=https://www.googleapis.com duration=373.481763ms May 25 11:16:05 forte volumio5-onboarding[1814]: time=2026-05-25T11:16:05.704-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%07,10.216.62.122:38250 @ 0x1a61aa0" latency=-537.293042ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=373.948737ms May 25 11:16:05 forte volumio5-onboarding[1814]: time=2026-05-25T11:16:05.704-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%07,10.216.62.122:38250 @ 0x1a61aa0" latency=-537.293042ms timeout=10s endpoint=https://database.volumio.cloud duration=373.154469ms May 25 11:16:05 forte volumio5-onboarding[1814]: time=2026-05-25T11:16:05.707-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%07,10.216.62.122:38250 @ 0x1a61aa0" latency=-537.293042ms timeout=10s endpoint=http://pushupdates.volumio.org duration=375.829323ms May 25 11:16:05 forte volumio5-onboarding[1814]: time=2026-05-25T11:16:05.756-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%07,10.216.62.122:38250 @ 0x1a61aa0" latency=-537.293042ms timeout=10s endpoint=https://google.com duration=425.979112ms May 25 11:16:06 forte volumio5-onboarding[1814]: time=2026-05-25T11:16:06.793-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%07,10.216.62.122:38250 @ 0x1a61aa0" latency=-537.293042ms timeout=10s endpoint=http://cddb.volumio.org duration=1.462620835s May 25 11:16:06 forte volumio5-onboarding[1814]: time=2026-05-25T11:16:06.797-05:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="00:00:00:00:00:00%07,10.216.62.122:38250 @ 0x1a61aa0" latency=-539.702414ms timeout=10s from=APP_PAGE_SETUP_V1_INTERNET to=APP_PAGE_LEGACY_DEVICE May 25 11:16:07 forte sudo[2918]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 11:16:07 forte sudo[2918]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:07 forte sudo[2918]: pam_unix(sudo:session): session closed for user root May 25 11:16:07 forte sudo[2920]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:16:07 forte sudo[2920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:07 forte sudo[2920]: pam_unix(sudo:session): session closed for user root May 25 11:16:07 forte volumio[1364]: verbose: New Socket.io Connection to 10.216.62.187 from 10.216.62.122 UA: Mozilla/5.0 (Linux; Android 16; CPH2749 Build/BP2A.250605.015; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.121 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::volumioGetVisibleSources May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:16:07 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 25 11:16:07 forte volumio[1364]: info: Received Get System Info May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 11:16:07 forte volumio[1364]: info: Discovery: Getting this device information May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:16:07 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:16:07 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:16:07 forte volumio[1364]: info: Listing playlists May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 11:16:07 forte sudo[2925]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 11:16:07 forte sudo[2925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:07 forte sudo[2927]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:16:07 forte sudo[2927]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:07 forte sudo[2925]: pam_unix(sudo:session): session closed for user root May 25 11:16:07 forte sudo[2927]: pam_unix(sudo:session): session closed for user root May 25 11:16:07 forte volumio[1364]: verbose: New Socket.io Connection to 10.216.62.187 from 10.216.62.122 UA: Mozilla/5.0 (Linux; Android 16; CPH2749 Build/BP2A.250605.015; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.121 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::volumioGetVisibleSources May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:16:07 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 25 11:16:07 forte volumio[1364]: info: Received Get System Info May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 11:16:07 forte volumio[1364]: info: Discovery: Getting this device information May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:16:07 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:16:07 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:16:07 forte volumio[1364]: info: Listing playlists May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 25 11:16:07 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 11:16:08 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart May 25 11:16:08 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart May 25 11:16:08 forte volumio[1364]: info: Refreshing Cached IP Addresses May 25 11:16:08 forte sudo[2931]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli May 25 11:16:08 forte sudo[2931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:08 forte sudo[2933]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 11:16:08 forte sudo[2933]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:08 forte sudo[2935]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:16:08 forte sudo[2935]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:08 forte sudo[2933]: pam_unix(sudo:session): session closed for user root May 25 11:16:08 forte sudo[2931]: pam_unix(sudo:session): session closed for user root May 25 11:16:08 forte sudo[2935]: pam_unix(sudo:session): session closed for user root May 25 11:16:08 forte systemd[1]: upmpdcli.service: Deactivated successfully. May 25 11:16:08 forte systemd[1]: upmpdcli.service: Consumed 5.229s CPU time. May 25 11:16:08 forte volumio[1364]: info: Discovery: Restarting Advertising May 25 11:16:09 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 25 11:16:09 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 11:16:09 forte volumio[1364]: info: Received Get System Info May 25 11:16:09 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 11:16:09 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 11:16:09 forte volumio[1364]: info: Discovery: Getting this device information May 25 11:16:09 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:16:09 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:16:09 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 11:16:09 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 25 11:16:12 forte volumio[1364]: info: Volumio Network Manager: Network status updated: 2 May 25 11:16:12 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 11:16:12 forte volumio[1364]: info: Received Get System Info May 25 11:16:12 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 11:16:12 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 11:16:12 forte volumio[1364]: info: Discovery: Getting this device information May 25 11:16:12 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:16:12 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:16:12 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 11:16:13 forte volumio[1364]: info: Discovery: Started advertising with name: Forte May 25 11:16:14 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 11:16:14 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 11:16:14 forte volumio[1364]: info: Discovery: Getting this device information May 25 11:16:14 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:16:14 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:16:14 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 11:16:14 forte volumio[1364]: verbose: New Socket.io Connection to 10.216.62.187:3000 from 10.216.62.122 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 May 25 11:16:14 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 11:16:14 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 11:16:14 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 11:16:14 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 11:16:14 forte volumio[1364]: info: Discovery: Getting this device information May 25 11:16:14 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:16:14 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:16:14 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 11:16:14 forte volumio[1364]: verbose: New Socket.io Connection to 10.216.62.187:3000 from 10.216.62.122 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 May 25 11:16:14 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 11:16:14 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 11:16:14 forte volumio[1364]: info: Discovery: adding 8b11f0bd-e69c-4369-95d7-9c2c6ae7fcb0 May 25 11:16:14 forte volumio[1364]: info: Discovery: Found device Forte May 25 11:16:14 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:16:14 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:16:14 forte volumio[1364]: info: Discovery: this is already registered, 8b11f0bd-e69c-4369-95d7-9c2c6ae7fcb0 May 25 11:16:14 forte volumio[1364]: info: Discovery: Found device Forte May 25 11:16:14 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:16:14 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:16:14 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 11:16:14 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 25 11:16:17 forte volumio[1364]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 11:16:17 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 25 11:16:18 forte sudo[2960]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 11:16:18 forte sudo[2960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:18 forte sudo[2962]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:16:18 forte sudo[2960]: pam_unix(sudo:session): session closed for user root May 25 11:16:18 forte sudo[2962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:18 forte sudo[2962]: pam_unix(sudo:session): session closed for user root May 25 11:16:18 forte sudo[2966]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 25 11:16:18 forte sudo[2966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:23 forte systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. May 25 11:16:23 forte systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 25 11:16:23 forte systemd[1]: upmpdcli.service: Consumed 5.229s CPU time. May 25 11:16:23 forte systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 25 11:16:23 forte sudo[2966]: pam_unix(sudo:session): session closed for user root May 25 11:16:23 forte volumio[1364]: info: Upmpdcli Daemon Started May 25 11:16:23 forte upmpdcli[2998]: writing RSA key May 25 11:16:26 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 11:16:26 forte volumio[1364]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 25 11:16:26 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 25 11:16:26 forte volumio[1364]: info: Received Get System Version May 25 11:16:26 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 11:16:26 forte volumio[1364]: info: Received Get System Info May 25 11:16:26 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 11:16:26 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 11:16:26 forte volumio[1364]: info: Discovery: Getting this device information May 25 11:16:26 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:16:26 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:16:26 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 11:16:41 forte volumio[1364]: info: Received OAUTH Data May 25 11:16:41 forte volumio[1364]: info: Executing Spotify Oauth Login May 25 11:16:41 forte volumio[1364]: info: Saving Spotify Refresh Token May 25 11:16:41 forte sudo[3022]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 11:16:41 forte sudo[3022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:41 forte sudo[3024]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 11:16:41 forte sudo[3024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:41 forte sudo[3022]: pam_unix(sudo:session): session closed for user root May 25 11:16:41 forte sudo[3024]: pam_unix(sudo:session): session closed for user root May 25 11:16:41 forte volumio[1364]: verbose: New Socket.io Connection to 10.216.62.187 from 10.216.62.122 UA: Mozilla/5.0 (Linux; Android 16; CPH2749 Build/BP2A.250605.015; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.121 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 25 11:16:41 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 11:16:41 forte volumio[1364]: info: CoreCommandRouter::volumioGetVisibleSources May 25 11:16:41 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 11:16:41 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:16:41 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:16:41 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 25 11:16:41 forte volumio[1364]: info: CoreCommandRouter::getUIConfigOnPlugin May 25 11:16:41 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 25 11:16:41 forte volumio[1364]: info: Received Get System Info May 25 11:16:41 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 11:16:41 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 11:16:41 forte volumio[1364]: info: Discovery: Getting this device information May 25 11:16:41 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:16:41 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:16:41 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 11:16:41 forte volumio[1364]: info: CoreCommandRouter::volumioGetState May 25 11:16:41 forte volumio[1364]: info: CorePlayQueue::getTrack 0 May 25 11:16:41 forte volumio[1364]: info: Listing playlists May 25 11:16:41 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 25 11:16:41 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 11:16:41 forte volumio[1364]: info: New Spotify access tokenBQBHj-db3y... May 25 11:16:41 forte volumio[1364]: info: Spotify credentials grant success - running version from March 24, 2019 May 25 11:16:41 forte volumio[1364]: SPOTIFY: User informations: {"country":"US","display_name":"Mitch Mennelle","email":"mitchmennelle@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/mmennelle1"},"followers":{"href":null,"total":5},"href":"https://api.spotify.com/v1/users/mmennelle1","id":"mmennelle1","images":[{"height":300,"url":"https://scontent-arn2-1.xx.fbcdn.net/v/t39.30808-1/475298360_10160557930781653_8911549193578704187_n.jpg?stp=dst-jpg_s320x320_tt6&_nc_cat=111&ccb=1-7&_nc_sid=08baa4&_nc_ohc=0ewbqquTcTEQ7kNvwGU7C67&_nc_oc=AdoezEggUcJI16iK4n3cMwHlrbETFMhH-IZTYeslW2ujOtkS5zZxqhoWbaoha-wIoTHc-P0YjdJPhBAgylX3470S&_nc_zt=24&_nc_ht=scontent-arn2-1.xx&edm=AP4hL3IEAAAA&_nc_gid=Gf8Vyr4KniV7SpGE776ojw&_nc_tpa=Q5bMBQFmRwexk_W-nSAOQ6M7DNicGySbxfs9eiMBOoQJJ-ZhPS4W3IW8rya-V-xCqfTJvVQ1Uhqi&oh=00_Af4hQQyks_i8RUqLwyjTvK6k7_A9RCl7BgDM-eDDMx5jdQ&oe=6A1A1363","width":300},{"height":64,"url":"https://scontent-arn2-1.xx.fbcdn.net/v/t39.30808-1/475298360_10160557930781653_8911549193578704187_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=111&ccb=1-7&_nc_sid=28885b&_nc_ohc=0ewbqquTcTEQ7kNvwGU7C67&_nc_oc=AdoezEggUcJI16iK4n3cMwHlrbETFMhH-IZTYeslW2ujOtkS5zZxqhoWbaoha-wIoTHc-P0YjdJPhBAgylX3470S&_nc_zt=24&_nc_ht=scontent-arn2-1.xx&edm=AP4hL3IEAAAA&_nc_gid=Gf8Vyr4KniV7SpGE776ojw&_nc_tpa=Q5bMBQGKhmuqLCupoj7J2OUJiZhuqmTCuD7XPTLqx66k7gtb1aeG8I04zP4UDeUawVs_0HzXLZRg&oh=00_Af7tOwGk6jHH441MwjwdlsF86DeUkbB3-bvxCDi1TYWkZw&oe=6A1A1363","width":64}],"product":"premium","type":"user","uri":"spotify:user:mmennelle1"} May 25 11:16:41 forte volumio[1364]: info: Creating Spotify config file May 25 11:16:41 forte volumio[1364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 11:16:41 forte volumio[1364]: info: Spotify config file written May 25 11:16:41 forte sudo[3028]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 25 11:16:41 forte sudo[3028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 11:16:42 forte systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... May 25 11:16:42 forte volumio[1364]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 11:16:42 forte systemd[1]: go-librespot-daemon.service: Deactivated successfully. May 25 11:16:42 forte systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 11:16:42 forte volumio[1364]: Error: socket hang up May 25 11:16:42 forte volumio[1364]: at connResetException (node:internal/errors:720:14) May 25 11:16:42 forte volumio[1364]: at Socket.socketOnEnd (node:_http_client:519:23) May 25 11:16:42 forte volumio[1364]: at Socket.emit (node:events:526:35) May 25 11:16:42 forte volumio[1364]: at endReadableNT (node:internal/streams/readable:1376:12) May 25 11:16:42 forte volumio[1364]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { May 25 11:16:42 forte volumio[1364]: code: 'ECONNRESET', May 25 11:16:42 forte volumio[1364]: response: undefined May 25 11:16:42 forte volumio[1364]: } May 25 11:16:42 forte volumio[1364]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 11:16:42 forte systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 11:16:42 forte go-librespot[3030]: go-librespot daemon starting... May 25 11:16:42 forte sudo[3028]: pam_unix(sudo:session): session closed for user root May 25 11:16:42 forte go-librespot[3042]: time="2026-05-25T11:16:42-05:00" level=info msg="running go-librespot 0.7.1" May 25 11:16:42 forte go-librespot[3042]: time="2026-05-25T11:16:42-05:00" level=debug msg="app state loaded" May 25 11:16:42 forte go-librespot[3042]: time="2026-05-25T11:16:42-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 11:16:42 forte sudo[3052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-25 11:15' May 25 11:16:42 forte sudo[3052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"