Feb 14 21:55:00 volumio sudo[1150]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:00 volumio wireless.js[739]: WIRELESS.JS - INFO: Start wireless flow
Feb 14 21:55:00 volumio wireless.js[739]: WIRELESS.JS - INFO: Stopped hotspot (if there)..
Feb 14 21:55:00 volumio sudo[1179]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Feb 14 21:55:00 volumio sudo[1179]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 14 21:55:00 volumio sudo[1179]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:00 volumio sudo[1181]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Feb 14 21:55:00 volumio sudo[1181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 14 21:55:01 volumio sudo[1181]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:01 volumio wireless.js[739]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations
Feb 14 21:55:01 volumio wireless.js[739]: WIRELESS.JS - INFO: STAGE 1: wlan0 validated and ready (MAC: d8:3a:dd:c2:75:9b, USB: false)
Feb 14 21:55:01 volumio wpa_supplicant[1187]: Successfully initialized wpa_supplicant
Feb 14 21:55:01 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Feb 14 21:55:01 volumio bash[1017]: setdatetime-helper: all HTTPS Date fallbacks failed
Feb 14 21:55:01 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully.
Feb 14 21:55:01 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Feb 14 21:55:02 volumio wpa_supplicant[1187]: nl80211: kernel reports: Registration to specific type not supported
Feb 14 21:55:02 volumio sudo[1194]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Feb 14 21:55:02 volumio sudo[1194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 14 21:55:02 volumio sudo[1194]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:03 volumio wireless.js[739]: WIRELESS.JS - INFO: DHCP IP fallback
Feb 14 21:55:03 volumio wireless.js[739]: WIRELESS.JS - INFO: STAGE 2: Starting event-driven WPA state monitor
Feb 14 21:55:03 volumio wireless.js[739]: WIRELESS.JS - INFO: WpaStateMachine: Starting state monitor for wlan0
Feb 14 21:55:03 volumio wireless.js[739]: WIRELESS.JS - INFO: WpaStateMachine: State transition: NULL -> SCANNING (duration: 0ms)
Feb 14 21:55:06 volumio volumio-remote-updater[737]: [2026-02-14 21:55:06] [info] asio async_connect error: asio.system:111 (Connection refused)
Feb 14 21:55:06 volumio volumio-remote-updater[737]: [2026-02-14 21:55:06] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected)
Feb 14 21:55:06 volumio volumio-remote-updater[737]: [2026-02-14 21:55:06] [error] handle_connect error: Connection refused
Feb 14 21:55:09 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1.
Feb 14 21:55:09 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 14 21:55:09 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 14 21:55:09 volumio upmpdcli[1238]: Could not open config: /tmp/upmpdcli.conf
Feb 14 21:55:09 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Feb 14 21:55:09 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Feb 14 21:55:15 volumio wpa_supplicant[1191]: wlan0: Trying to associate with 14:a7:2b:58:9d:ed (SSID='FTTH-5G' freq=5200 MHz)
Feb 14 21:55:15 volumio wpa_supplicant[1191]: FT: Invalid key management type (2)
Feb 14 21:55:15 volumio wireless.js[739]: WIRELESS.JS - INFO: WpaStateMachine: State transition: SCANNING -> ASSOCIATING (duration: 12196ms)
Feb 14 21:55:15 volumio wpa_supplicant[1191]: wlan0: Associated with 14:a7:2b:58:9d:ed
Feb 14 21:55:15 volumio wpa_supplicant[1191]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Feb 14 21:55:15 volumio wpa_supplicant[1191]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US
Feb 14 21:55:16 volumio wireless.js[739]: WIRELESS.JS - INFO: WpaStateMachine: State transition: ASSOCIATING -> 4WAY_HANDSHAKE (duration: 510ms)
Feb 14 21:55:16 volumio volumio-remote-updater[737]: [2026-02-14 21:55:16] [info] asio async_connect error: asio.system:111 (Connection refused)
Feb 14 21:55:16 volumio volumio-remote-updater[737]: [2026-02-14 21:55:16] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected)
Feb 14 21:55:16 volumio volumio-remote-updater[737]: [2026-02-14 21:55:16] [error] handle_connect error: Connection refused
Feb 14 21:55:16 volumio wpa_supplicant[1191]: wlan0: WPA: Key negotiation completed with 14:a7:2b:58:9d:ed [PTK=CCMP GTK=TKIP]
Feb 14 21:55:16 volumio wpa_supplicant[1191]: wlan0: CTRL-EVENT-CONNECTED - Connection to 14:a7:2b:58:9d:ed completed [id=1 id_str=]
Feb 14 21:55:16 volumio dhcpcd[793]: wlan0: carrier acquired
Feb 14 21:55:16 volumio dhcpcd[793]: wlan0: connected to Access Point: FTTH-5G
Feb 14 21:55:16 volumio dhcpcd[793]: wlan0: IAID dd:c2:75:9b
Feb 14 21:55:16 volumio dhcpcd[793]: wlan0: adding address fe80::da3a:ddff:fec2:759b
Feb 14 21:55:16 volumio dhcpcd[793]: ipv6_addaddr1: Permission denied
Feb 14 21:55:17 volumio wireless.js[739]: WIRELESS.JS - INFO: WpaStateMachine: State transition: 4WAY_HANDSHAKE -> COMPLETED (duration: 1015ms)
Feb 14 21:55:17 volumio wireless.js[739]: WIRELESS.JS - INFO: WpaStateMachine: COMPLETED - connection successful
Feb 14 21:55:17 volumio wireless.js[739]: WIRELESS.JS - INFO: STAGE 2: Connection successful - Connected to 14:a7:2b:58:9d:ed
Feb 14 21:55:17 volumio wireless.js[739]: WIRELESS.JS - INFO: Onboard WiFi adapter detected, using standard dhcpcd flow
Feb 14 21:55:17 volumio dhcpcd[793]: wlan0: soliciting an IPv6 router
Feb 14 21:55:18 volumio sudo[1291]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd wlan0
Feb 14 21:55:18 volumio sudo[1291]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 14 21:55:18 volumio dhcpcd[793]: control command: /sbin/dhcpcd wlan0
Feb 14 21:55:18 volumio dhcpcd[793]: control_free: No such file or directory
Feb 14 21:55:18 volumio sudo[1291]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:18 volumio dhcpcd[793]: wlan0: rebinding lease of 192.168.1.36
Feb 14 21:55:18 volumio dhcpcd[793]: wlan0: probing address 192.168.1.36/24
Feb 14 21:55:20 volumio wireless.js[739]: WIRELESS.JS - INFO: Start ap
Feb 14 21:55:20 volumio wireless.js[739]: WIRELESS.JS - INFO: Notified systemd about wireless ready
Feb 14 21:55:20 volumio systemd[1]: Started wireless.service - Wireless Services.
Feb 14 21:55:20 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Feb 14 21:55:20 volumio systemd[1]: Started volumio.service - Volumio Backend Module.
Feb 14 21:55:20 volumio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen...
Feb 14 21:55:20 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker.
Feb 14 21:55:20 volumio systemd[1]: Received SIGRTMIN+21 from PID 333 (plymouthd).
Feb 14 21:55:20 volumio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up.
Feb 14 21:55:20 volumio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen.
Feb 14 21:55:20 volumio systemd[1]: Received SIGRTMIN+21 from PID 333 (plymouthd).
Feb 14 21:55:20 volumio volumio-cpu-tweak[1303]: Setting RT Priority for mpd
Feb 14 21:55:20 volumio volumio-cpu-tweak[1319]: pid 35's current scheduling policy: SCHED_OTHER
Feb 14 21:55:20 volumio volumio-cpu-tweak[1319]: pid 35's current scheduling priority: 0
Feb 14 21:55:20 volumio volumio-cpu-tweak[1303]: Setting MPD Affinity
Feb 14 21:55:20 volumio volumio-cpu-tweak[1320]: pid 3's current affinity mask: f
Feb 14 21:55:20 volumio volumio-cpu-tweak[1303]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input
Feb 14 21:55:20 volumio volumio-cpu-tweak[1303]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance
Feb 14 21:55:20 volumio systemd[1]: Started getty@tty1.service - Getty on tty1.
Feb 14 21:55:20 volumio systemd[1]: Reached target getty.target - Login Prompts.
Feb 14 21:55:20 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully.
Feb 14 21:55:21 volumio wireless.js[739]: WIRELESS.JS - INFO: trying...
Feb 14 21:55:21 volumio sudo[1332]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Feb 14 21:55:21 volumio sudo[1332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 14 21:55:21 volumio sudo[1332]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:21 volumio sudo[1335]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 14 21:55:21 volumio sudo[1335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 14 21:55:21 volumio sudo[1335]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:21 volumio wireless.js[739]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined
Feb 14 21:55:21 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully.
Feb 14 21:55:22 volumio wireless.js[739]: WIRELESS.JS - INFO: trying...
Feb 14 21:55:22 volumio sudo[1346]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Feb 14 21:55:22 volumio sudo[1346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 14 21:55:22 volumio sudo[1346]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:22 volumio sudo[1350]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 14 21:55:22 volumio sudo[1350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 14 21:55:22 volumio sudo[1350]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:22 volumio wireless.js[739]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined
Feb 14 21:55:23 volumio volumio[1301]: info: -------------------------------------------
Feb 14 21:55:23 volumio dhcpcd[693]: timed out
Feb 14 21:55:23 volumio sh[687]: timed out
Feb 14 21:55:23 volumio volumio[1301]: info: ----- Volumio3 ----
Feb 14 21:55:23 volumio volumio[1301]: info: -------------------------------------------
Feb 14 21:55:23 volumio volumio[1301]: info: ----- System startup ----
Feb 14 21:55:23 volumio volumio[1301]: info: -------------------------------------------
Feb 14 21:55:23 volumio sh[635]: ifup: failed to bring up eth0
Feb 14 21:55:23 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE
Feb 14 21:55:23 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'.
Feb 14 21:55:23 volumio wireless.js[739]: WIRELESS.JS - INFO: WARNING: dhcpcd running but no IP assigned yet
Feb 14 21:55:23 volumio wireless.js[739]: WIRELESS.JS - INFO: trying...
Feb 14 21:55:23 volumio sudo[1373]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Feb 14 21:55:23 volumio sudo[1373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 14 21:55:23 volumio sudo[1373]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:23 volumio sudo[1378]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 14 21:55:23 volumio sudo[1378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 14 21:55:23 volumio sudo[1378]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:23 volumio wireless.js[739]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined
Feb 14 21:55:24 volumio dhcpcd[793]: wlan0: leased 192.168.1.36 for 30 seconds
Feb 14 21:55:24 volumio avahi-daemon[721]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.36.
Feb 14 21:55:24 volumio avahi-daemon[721]: New relevant interface wlan0.IPv4 for mDNS.
Feb 14 21:55:24 volumio dhcpcd[793]: wlan0: adding route to 192.168.1.0/24
Feb 14 21:55:24 volumio dhcpcd[793]: wlan0: adding default route via 192.168.1.1
Feb 14 21:55:24 volumio avahi-daemon[721]: Registering new address record for 192.168.1.36 on wlan0.IPv4.
Feb 14 21:55:24 volumio systemd[1]: welcome.service: Deactivated successfully.
Feb 14 21:55:24 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console.
Feb 14 21:55:24 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console...
Feb 14 21:55:24 volumio systemd[1]: Starting welcome.service - Show a welcome message on console...
Feb 14 21:55:24 volumio welcome[1389]: Resolved ip:[1] 192.168.1.36
Feb 14 21:55:24 volumio systemd[1]: Finished welcome.service - Show a welcome message on console.
Feb 14 21:55:24 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0.
Feb 14 21:55:24 volumio systemd[1]: Started nmbd.service - Samba NMB Daemon.
Feb 14 21:55:24 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon...
Feb 14 21:55:24 volumio volumio[1301]: info: MYVOLUMIO Environment detected
Feb 14 21:55:24 volumio wireless.js[739]: WIRELESS.JS - INFO: trying...
Feb 14 21:55:24 volumio sudo[1423]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Feb 14 21:55:24 volumio sudo[1423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 14 21:55:24 volumio volumio[1301]: info: Plugin folders cleanup
Feb 14 21:55:24 volumio volumio[1301]: info: Scanning into folder /volumio/app/plugins/
Feb 14 21:55:24 volumio volumio[1301]: info: Scanning category audio_interface
Feb 14 21:55:24 volumio sudo[1423]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:24 volumio volumio[1301]: info: Scanning category miscellanea
Feb 14 21:55:24 volumio volumio[1301]: info: Scanning category music_service
Feb 14 21:55:24 volumio volumio[1301]: info: Scanning category plugins.json
Feb 14 21:55:24 volumio volumio[1301]: info: Scanning category system_controller
Feb 14 21:55:24 volumio volumio[1301]: info: Scanning category user_interface
Feb 14 21:55:24 volumio volumio[1301]: info: Scanning into folder /data/plugins/
Feb 14 21:55:24 volumio volumio[1301]: info: Scanning category music_service
Feb 14 21:55:24 volumio volumio[1301]: info: Scanning category system_controller
Feb 14 21:55:24 volumio volumio[1301]: info: Plugin folders cleanup completed
Feb 14 21:55:24 volumio volumio[1301]: info: -------------------------------------------
Feb 14 21:55:24 volumio volumio[1301]: info: ----- Core plugins startup ----
Feb 14 21:55:24 volumio volumio[1301]: info: -------------------------------------------
Feb 14 21:55:24 volumio volumio[1301]: info: Loading plugins from folder /volumio/app/plugins/
Feb 14 21:55:24 volumio volumio[1301]: info: Adding plugin upnp to MyMusic Plugins
Feb 14 21:55:24 volumio volumio[1301]: info: Adding plugin airplay_emulation to MyMusic Plugins
Feb 14 21:55:24 volumio volumio[1301]: info: Adding plugin upnp_browser to MyMusic Plugins
Feb 14 21:55:24 volumio volumio[1301]: info: Loading plugins from folder /data/plugins/
Feb 14 21:55:24 volumio sudo[1426]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 14 21:55:24 volumio sudo[1426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 14 21:55:24 volumio volumio[1301]: info: Loading plugin "system"...
Feb 14 21:55:24 volumio sudo[1426]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:24 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2.
Feb 14 21:55:24 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Feb 14 21:55:24 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 14 21:55:24 volumio winbindd[1413]: [2026/02/14 21:55:24.751218, 0] ../../source3/winbindd/winbindd.c:1440(main)
Feb 14 21:55:24 volumio winbindd[1413]: winbindd version 4.17.8-Raspbian started.
Feb 14 21:55:24 volumio winbindd[1413]: Copyright Andrew Tridgell and the Samba Team 1992-2022
Feb 14 21:55:24 volumio wireless.js[739]: WIRELESS.JS - INFO: ... wlan0 IPv4 is 192.168.1.36, ipV6 is undefined
Feb 14 21:55:24 volumio wireless.js[739]: WIRELESS.JS - INFO: Connected to SSID: FTTH-5G
Feb 14 21:55:24 volumio wireless.js[739]: WIRELESS.JS - INFO: It's done! AP
Feb 14 21:55:24 volumio volumio[1301]: info: Loading plugin "appearance"...
Feb 14 21:55:24 volumio winbindd[1413]: [2026/02/14 21:55:24.773415, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache)
Feb 14 21:55:24 volumio winbindd[1413]: initialize_winbindd_cache: clearing cache and re-creating with version number 2
Feb 14 21:55:24 volumio wireless.js[739]: WIRELESS.JS - INFO: Restarting avahi-daemon...
Feb 14 21:55:24 volumio sudo[1438]: root : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart avahi-daemon
Feb 14 21:55:24 volumio sudo[1438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 14 21:55:24 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 14 21:55:24 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon.
Feb 14 21:55:24 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon...
Feb 14 21:55:24 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 14 21:55:24 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 14 21:55:24 volumio upmpdcli[1430]: Could not open config: /tmp/upmpdcli.conf
Feb 14 21:55:24 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 14 21:55:24 volumio systemd[1]: shairport-sync.service: Consumed 1.416s CPU time.
Feb 14 21:55:24 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Feb 14 21:55:24 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Feb 14 21:55:24 volumio avahi-daemon[721]: Got SIGTERM, quitting.
Feb 14 21:55:24 volumio avahi-daemon[721]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.36.
Feb 14 21:55:24 volumio avahi-daemon[721]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1.
Feb 14 21:55:24 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack...
Feb 14 21:55:24 volumio avahi-daemon[721]: avahi-daemon 0.8 exiting.
Feb 14 21:55:24 volumio systemd[1]: avahi-daemon.service: Deactivated successfully.
Feb 14 21:55:24 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack.
Feb 14 21:55:24 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack...
Feb 14 21:55:24 volumio avahi-daemon[1444]: Process 721 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid)
Feb 14 21:55:24 volumio avahi-daemon[1444]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109).
Feb 14 21:55:24 volumio avahi-daemon[1444]: Successfully dropped root privileges.
Feb 14 21:55:24 volumio avahi-daemon[1444]: avahi-daemon 0.8 starting up.
Feb 14 21:55:24 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack.
Feb 14 21:55:24 volumio avahi-daemon[1444]: Successfully called chroot().
Feb 14 21:55:24 volumio avahi-daemon[1444]: Successfully dropped remaining capabilities.
Feb 14 21:55:24 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 14 21:55:25 volumio sudo[1438]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:25 volumio avahi-daemon[1444]: No service file found in /etc/avahi/services.
Feb 14 21:55:25 volumio avahi-daemon[1444]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.36.
Feb 14 21:55:25 volumio avahi-daemon[1444]: New relevant interface wlan0.IPv4 for mDNS.
Feb 14 21:55:25 volumio avahi-daemon[1444]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1.
Feb 14 21:55:25 volumio avahi-daemon[1444]: New relevant interface lo.IPv4 for mDNS.
Feb 14 21:55:25 volumio avahi-daemon[1444]: Network interface enumeration completed.
Feb 14 21:55:25 volumio avahi-daemon[1444]: Registering new address record for 192.168.1.36 on wlan0.IPv4.
Feb 14 21:55:25 volumio avahi-daemon[1444]: Registering new address record for 127.0.0.1 on lo.IPv4.
Feb 14 21:55:25 volumio wireless.js[739]: WIRELESS.JS - INFO: Notified systemd about wireless ready
Feb 14 21:55:25 volumio ntpd[1061]: IO: Listen normally on 3 wlan0 192.168.1.36:123
Feb 14 21:55:25 volumio ntpd[1061]: IO: new interface(s) found: waking up resolver
Feb 14 21:55:25 volumio ntpd[1061]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101
Feb 14 21:55:25 volumio smbd[1457]: [2026/02/14 21:55:25.327996, 0] ../../source3/smbd/server.c:1741(main)
Feb 14 21:55:25 volumio smbd[1457]: smbd version 4.17.8-Raspbian started.
Feb 14 21:55:25 volumio smbd[1457]: Copyright Andrew Tridgell and the Samba Team 1992-2022
Feb 14 21:55:25 volumio avahi-daemon[1444]: Server startup complete. Host name is volumio.local. Local service cookie is 3881646565.
Feb 14 21:55:26 volumio volumio-remote-updater[737]: [2026-02-14 21:55:26] [connect] Successful connection
Feb 14 21:55:26 volumio systemd[1]: Started smbd.service - Samba SMB Daemon.
Feb 14 21:55:26 volumio systemd[1]: Reached target multi-user.target - Multi-User System.
Feb 14 21:55:26 volumio systemd[1]: Reached target graphical.target - Graphical Interface.
Feb 14 21:55:26 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP...
Feb 14 21:55:26 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully.
Feb 14 21:55:26 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP.
Feb 14 21:55:26 volumio systemd[1]: Startup finished in 13.379s (kernel) + 36.860s (userspace) = 50.240s.
Feb 14 21:55:27 volumio volumio[1301]: info: Loading plugin "network"...
Feb 14 21:55:27 volumio volumio[1301]: info: Refreshing Cached IP Addresses
Feb 14 21:55:27 volumio sudo[1490]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Feb 14 21:55:27 volumio sudo[1490]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 14 21:55:27 volumio volumio[1301]: info: Loading plugin "services"...
Feb 14 21:55:27 volumio sudo[1490]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:27 volumio volumio[1301]: info: Loading plugin "volumio5onboarding"...
Feb 14 21:55:27 volumio volumio[1301]: info: Loading plugin "alsa_controller"...
Feb 14 21:55:27 volumio sudo[1503]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Feb 14 21:55:27 volumio sudo[1503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 14 21:55:27 volumio sudo[1491]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 14 21:55:27 volumio sudo[1491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 14 21:55:27 volumio sudo[1491]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:27 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 14 21:55:27 volumio volumio[1301]: info: Loading plugin "wizard"...
Feb 14 21:55:27 volumio volumio[1301]: info: Loading plugin "networkfs"...
Feb 14 21:55:27 volumio volumio[1301]: info: Starting Udev Watcher for removable devices
Feb 14 21:55:27 volumio volumio[1301]: info: Ignoring mount for partition: boot
Feb 14 21:55:27 volumio volumio[1301]: info: Ignoring mount for partition: volumio
Feb 14 21:55:27 volumio volumio[1301]: info: Ignoring mount for partition: volumio_data
Feb 14 21:55:27 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Feb 14 21:55:27 volumio volumio[1301]: info: Loading plugin "volumio_command_line_client"...
Feb 14 21:55:27 volumio volumio[1301]: info: Loading plugin "upnp"...
Feb 14 21:55:27 volumio volumio[1301]: info: [1771086327928] Starting Upmpd Daemon
Feb 14 21:55:27 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Feb 14 21:55:27 volumio volumio[1301]: info: Loading plugin "my_music"...
Feb 14 21:55:27 volumio volumio[1301]: info: Loading plugin "mpd"...
Feb 14 21:55:28 volumio volumio[1301]: info: Loading plugin "upnp_browser"...
Feb 14 21:55:29 volumio volumio[1301]: info: Starting UPNP Browser
Feb 14 21:55:29 volumio volumio[1301]: info: Loading plugin "alarm-clock"...
Feb 14 21:55:29 volumio volumio[1301]: info: Loading plugin "airplay_emulation"...
Feb 14 21:55:29 volumio volumio[1301]: info: Starting Shairport Sync
Feb 14 21:55:29 volumio volumio[1301]: info: Loading plugin "last_100"...
Feb 14 21:55:29 volumio volumio[1301]: info: Loading plugin "webradio"...
Feb 14 21:55:29 volumio volumio[1301]: info: Loading plugin "i2s_dacs"...
Feb 14 21:55:29 volumio volumio[1301]: info: Loading plugin "volumiodiscovery"...
Feb 14 21:55:29 volumio volumio[1301]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Feb 14 21:55:29 volumio volumio[1301]: *** WARNING *** Please fix your application to use the native API of Avahi!
Feb 14 21:55:29 volumio volumio[1301]: *** WARNING *** For more information see
Feb 14 21:55:29 volumio volumio[1301]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Feb 14 21:55:29 volumio volumio[1301]: *** WARNING *** Please fix your application to use the native API of Avahi!
Feb 14 21:55:29 volumio node[1301]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Feb 14 21:55:29 volumio volumio[1301]: *** WARNING *** For more information see
Feb 14 21:55:29 volumio node[1301]: *** WARNING *** Please fix your application to use the native API of Avahi!
Feb 14 21:55:29 volumio node[1301]: *** WARNING *** For more information see
Feb 14 21:55:29 volumio node[1301]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Feb 14 21:55:29 volumio node[1301]: *** WARNING *** Please fix your application to use the native API of Avahi!
Feb 14 21:55:29 volumio node[1301]: *** WARNING *** For more information see
Feb 14 21:55:29 volumio volumio[1301]: info: Applying required configuration parameters for plugin volumiodiscovery
Feb 14 21:55:29 volumio volumio[1301]: info: Discovery: Started advertising with name: Volumio
Feb 14 21:55:30 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Feb 14 21:55:30 volumio volumio[1301]: info: Loading plugin "spop"...
Feb 14 21:55:31 volumio volumio[1301]: info: Loading plugin "ytmusic"...
Feb 14 21:55:31 volumio sudo[1503]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:32 volumio volumio[1301]: info: Loading plugin "autostart"...
Feb 14 21:55:32 volumio volumio[1301]: info: Applying required configuration parameters for plugin autostart
Feb 14 21:55:32 volumio volumio[1301]: info: AutoStart - onVolumioStart - read config.json
Feb 14 21:55:32 volumio volumio[1301]: info: Loading plugin "outputs"...
Feb 14 21:55:32 volumio volumio[1301]: info: Loading plugin "albumart"...
Feb 14 21:55:32 volumio volumio[1301]: info: Plugin example_plugin is not enabled
Feb 14 21:55:32 volumio volumio[1301]: info: Loading plugin "inputs"...
Feb 14 21:55:32 volumio volumio[1301]: info: Loading plugin "updater_comm"...
Feb 14 21:55:32 volumio volumio[1301]: info: Plugin mpdemulation is not enabled
Feb 14 21:55:32 volumio volumio[1301]: info: Loading plugin "rest_api"...
Feb 14 21:55:32 volumio volumio[1301]: info: Loading plugin "websocket"...
Feb 14 21:55:32 volumio volumio[1301]: info: Starting Socket.io Server version 1.7.4
Feb 14 21:55:32 volumio volumio[1301]: info: Loading plugin "RoonBridge"...
Feb 14 21:55:33 volumio volumio[1301]: info: Applying required configuration parameters for plugin RoonBridge
Feb 14 21:55:33 volumio volumio[1524]: Forking 3 albumart workers
Feb 14 21:55:33 volumio volumio[1301]: info: Loading plugin "motherearthradio"...
Feb 14 21:55:34 volumio volumio[1301]: info: Applying required configuration parameters for plugin motherearthradio
Feb 14 21:55:34 volumio volumio[1301]: info: [1771086334753] [MotherEarth] API delay: 5
Feb 14 21:55:34 volumio volumio[1301]: info: Loading plugin "backup_restore"...
Feb 14 21:55:36 volumio volumio[1301]: info: Applying required configuration parameters for plugin backup_restore
Feb 14 21:55:36 volumio volumio[1301]: info: Loading i18n strings for locale en
Feb 14 21:55:36 volumio volumio[1301]: Updating browse sources language
Feb 14 21:55:36 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 14 21:55:37 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 14 21:55:37 volumio volumio[1301]: info: CoreCommandRouter::initPlayerControls
Feb 14 21:55:37 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:37 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:37 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:37 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:37 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:37 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:37 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:37 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:37 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 14 21:55:37 volumio volumio[1301]: Express server listening on port 3000
Feb 14 21:55:37 volumio volumio[1301]: [Metrics] WebUI: 14s 981.07ms
Feb 14 21:55:37 volumio volumio[1301]: info: CoreStateMachine::resetVolumioState
Feb 14 21:55:37 volumio volumio[1301]: info: CoreStateMachine::getcurrentVolume
Feb 14 21:55:37 volumio volumio[1301]: info: CoreCommandRouter::volumioRetrievevolume
Feb 14 21:55:38 volumio volumio[1301]: info: Volumio Network Manager: Network status updated: 2
Feb 14 21:55:38 volumio sudo[1583]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Feb 14 21:55:38 volumio sudo[1583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 14 21:55:38 volumio sudo[1583]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:38 volumio volumio[1536]: Starting albumart workers
Feb 14 21:55:38 volumio sudo[1585]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 14 21:55:38 volumio sudo[1585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 14 21:55:38 volumio sudo[1585]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:38 volumio volumio[1535]: Starting albumart workers
Feb 14 21:55:38 volumio volumio[1534]: Starting albumart workers
Feb 14 21:55:39 volumio volumio[1301]: info: VolumeController:: Volume=100 Mute =false
Feb 14 21:55:39 volumio volumio[1301]: info: CoreStateMachine::pushState
Feb 14 21:55:39 volumio volumio[1301]: info: CorePlayQueue::getTrack 0
Feb 14 21:55:39 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 14 21:55:39 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState
Feb 14 21:55:39 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service...
Feb 14 21:55:39 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0.
Feb 14 21:55:39 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0...
Feb 14 21:55:39 volumio systemd[1]: welcome.service: Deactivated successfully.
Feb 14 21:55:39 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console.
Feb 14 21:55:39 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console...
Feb 14 21:55:39 volumio volumio[1301]: info: CoreStateMachine::updateTrackBlock
Feb 14 21:55:39 volumio volumio[1301]: info: CorePlayQueue::getTrackBlock
Feb 14 21:55:39 volumio volumio[1301]: info: CoreCommandRouter::volumioRetrievevolume
Feb 14 21:55:39 volumio systemd[1]: Starting welcome.service - Show a welcome message on console...
Feb 14 21:55:39 volumio welcome[1613]: Resolved ip:[1] 192.168.1.36
Feb 14 21:55:39 volumio systemd[1]: Finished welcome.service - Show a welcome message on console.
Feb 14 21:55:39 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0.
Feb 14 21:55:39 volumio volumio[1301]: info: Reloading queue from file
Feb 14 21:55:39 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 14 21:55:39 volumio volumio[1301]: info: CoreStateMachine::setRepeat false single undefined
Feb 14 21:55:39 volumio volumio[1301]: info: CoreStateMachine::pushState
Feb 14 21:55:39 volumio volumio[1301]: info: CorePlayQueue::getTrack 0
Feb 14 21:55:39 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 14 21:55:39 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState
Feb 14 21:55:39 volumio volumio[1301]: info: CoreStateMachine::setRandom null
Feb 14 21:55:39 volumio volumio[1301]: info: CoreStateMachine::pushState
Feb 14 21:55:39 volumio volumio[1301]: info: CorePlayQueue::getTrack 0
Feb 14 21:55:39 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState
Feb 14 21:55:39 volumio volumio[1301]: info: Setting Device type: Raspberry PI
Feb 14 21:55:39 volumio volumio[1301]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb
Feb 14 21:55:39 volumio volumio[1301]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115
Feb 14 21:55:39 volumio volumio[1301]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI
Feb 14 21:55:39 volumio volumio[1301]: info: Completed loading Core Plugins
Feb 14 21:55:39 volumio volumio[1301]: info: Preparing to generate the ALSA configuration file
Feb 14 21:55:39 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3.
Feb 14 21:55:39 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 14 21:55:39 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 14 21:55:40 volumio upmpdcli[1643]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.1.36 port 49152 IPV6 port 49152
Feb 14 21:55:40 volumio upmpdcli[1643]: :3:../src/mediaserver/cdplugins/plgwithslave.cxx:238::PlgWithSlave: starting streaming proxy on port 49149
Feb 14 21:55:40 volumio upmpdcli[1643]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Cannot assign requested address
Feb 14 21:55:40 volumio upmpdcli[1643]: :2:../src/main.cxx:728::MPD connection failed
Feb 14 21:55:40 volumio volumio[1301]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf
Feb 14 21:55:40 volumio volumio[1301]: info: Reading ALSA contributions from plugins.
Feb 14 21:55:40 volumio volumio[1301]: info: VolumeController:: Volume=100 Mute =false
Feb 14 21:55:40 volumio volumio[1301]: info: CoreStateMachine::pushState
Feb 14 21:55:40 volumio volumio[1301]: info: CorePlayQueue::getTrack 0
Feb 14 21:55:40 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState
Feb 14 21:55:40 volumio sudo[1657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Feb 14 21:55:40 volumio sudo[1657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 14 21:55:40 volumio sudo[1657]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:40 volumio volumio[1301]: info: Upmpdcli Daemon Started
Feb 14 21:55:40 volumio volumio[1301]: info: Asound.conf file unchanged, so no further update is needed
Feb 14 21:55:40 volumio volumio[1301]: info: Output device has changed, restarting MPD
Feb 14 21:55:40 volumio sudo[1661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 14 21:55:40 volumio sudo[1661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 14 21:55:40 volumio volumio[1301]: info: Output device has changed, restarting Shairport Sync
Feb 14 21:55:40 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:40 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 14 21:55:40 volumio sudo[1661]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:40 volumio sudo[1664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 14 21:55:40 volumio sudo[1664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 14 21:55:40 volumio volumio[1301]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 14 21:55:40 volumio volumio[1301]: info: ___________ START PLUGINS ___________
Feb 14 21:55:40 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 14 21:55:40 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 14 21:55:40 volumio volumio[1301]: info: ControllerMpd::onStart: Initializing MPD
Feb 14 21:55:40 volumio volumio[1301]: info: Creating MPD Configuration file
Feb 14 21:55:40 volumio sudo[1672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service
Feb 14 21:55:40 volumio sudo[1673]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 14 21:55:40 volumio sudo[1673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 14 21:55:41 volumio sudo[1676]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Feb 14 21:55:41 volumio sudo[1673]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:41 volumio sudo[1675]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 14 21:55:41 volumio sudo[1675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 14 21:55:41 volumio sudo[1672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 14 21:55:41 volumio sudo[1675]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:41 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 14 21:55:41 volumio volumio[1301]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 14 21:55:41 volumio volumio[1301]: info: [1771086341061] CoreMusicLibrary::Adding element Media Servers
Feb 14 21:55:41 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 14 21:55:41 volumio volumio[1301]: info: UPNP Browser: Client initialized successfully
Feb 14 21:55:41 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server.
Feb 14 21:55:41 volumio sudo[1672]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:41 volumio sudo[1681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 14 21:55:41 volumio sudo[1681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 14 21:55:41 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:41 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 14 21:55:41 volumio systemd[1]: mpd.service: Deactivated successfully.
Feb 14 21:55:41 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Feb 14 21:55:41 volumio systemd[1]: mpd.socket: Deactivated successfully.
Feb 14 21:55:41 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Feb 14 21:55:41 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Feb 14 21:55:41 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 14 21:55:41 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 14 21:55:41 volumio volumio[1301]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 14 21:55:41 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:41 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 14 21:55:41 volumio sudo[1692]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 14 21:55:41 volumio sudo[1692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 14 21:55:41 volumio sudo[1698]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Feb 14 21:55:41 volumio sudo[1692]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:41 volumio volumio[1301]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 14 21:55:41 volumio volumio[1301]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 14 21:55:41 volumio volumio[1301]: info: [1771086341618] CoreMusicLibrary::Adding element Last_100
Feb 14 21:55:41 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 14 21:55:41 volumio volumio[1301]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 14 21:55:41 volumio volumio[1301]: info: [1771086341621] CoreMusicLibrary::Adding element Webradio
Feb 14 21:55:41 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 14 21:55:41 volumio volumio-remote-updater[737]: [2026-02-14 21:55:41] [connect] Successful connection
Feb 14 21:55:41 volumio volumio5-onboarding[1682]: time=2026-02-14T21:55:41.690+05:30 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z
Feb 14 21:55:41 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 14 21:55:41 volumio volumio[1301]: info: Initializing BBC Radios
Feb 14 21:55:42 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 14 21:55:42 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 14 21:55:42 volumio volumio[1301]: info: Creating Spotify config file
Feb 14 21:55:42 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:42 volumio volumio[1301]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 14 21:55:42 volumio volumio[1301]: info: [1771086342484] CoreMusicLibrary::Adding element YouTube Music
Feb 14 21:55:42 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 14 21:55:42 volumio volumio[1301]: Cannot find translation for source YouTube Music
Feb 14 21:55:42 volumio volumio[1301]: info: AutoStart - onStart - waiting for system ready state
Feb 14 21:55:42 volumio volumio[1301]: info: AutoStart - Polling config: interval=5000ms, maxAttempts=60
Feb 14 21:55:42 volumio volumio[1301]: info: AutoStart - Maximum wait time: 300 seconds
Feb 14 21:55:42 volumio volumio[1301]: info: AutoStart - Startup volume disabled
Feb 14 21:55:42 volumio volumio[1301]: info: AutoStart - Check #1/60 - VOLUMIO_SYSTEM_STATUS = starting
Feb 14 21:55:42 volumio volumio[1301]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 14 21:55:42 volumio volumio[1301]: info: [1771086342609] CoreMusicLibrary::Adding element Mother Earth Radio
Feb 14 21:55:42 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 14 21:55:42 volumio volumio[1301]: Cannot find translation for source YouTube Music
Feb 14 21:55:42 volumio volumio[1301]: Cannot find translation for source Mother Earth Radio
Feb 14 21:55:42 volumio volumio[1301]: info: Volumio Calling Home
Feb 14 21:55:42 volumio sudo[1736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service
Feb 14 21:55:42 volumio sudo[1736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 14 21:55:42 volumio systemd[1]: /lib/systemd/system/roonbridge.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 14 21:55:42 volumio systemd[1]: /lib/systemd/system/roonbridge.service:8: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 14 21:55:42 volumio systemd[1]: Started roonbridge.service - RoonBridge.
Feb 14 21:55:42 volumio sudo[1736]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:44 volumio upmpdcli[1643]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout
Feb 14 21:55:44 volumio upmpdcli[1643]: :2:../src/main.cxx:728::MPD connection failed
Feb 14 21:55:44 volumio RoonBridge[1744]: 00:00:00.006 Warn: get lock file path: /tmp/.rnbgem0-
Feb 14 21:55:44 volumio RoonBridge[1744]: 00:00:00.412 Trace: [childprocess] using unix child process
Feb 14 21:55:44 volumio RoonBridge[1744]: 00:00:00.500 Debug: PathForResource, filename: ../.update
Feb 14 21:55:44 volumio RoonBridge[1744]: 00:00:00.637 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update
Feb 14 21:55:44 volumio RoonBridge[1744]: 00:00:00.643 Debug: PathForResource, filename: RAATServer
Feb 14 21:55:44 volumio RoonBridge[1744]: 00:00:00.643 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer
Feb 14 21:55:44 volumio RoonBridge[1744]: 00:00:00.644 Debug: PathForResource, filename: RoonBridgeHelper
Feb 14 21:55:44 volumio RoonBridge[1744]: 00:00:00.644 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper
Feb 14 21:55:45 volumio RoonBridge[1744]: Initializing
Feb 14 21:55:45 volumio RoonBridge[1744]: 00:00:00.685 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper
Feb 14 21:55:45 volumio RoonBridge[1744]: 00:00:00.717 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer
Feb 14 21:55:45 volumio RoonBridge[1744]: Not Running (.o)
Feb 14 21:55:45 volumio volumio[1301]: info: MPD Permissions set
Feb 14 21:55:45 volumio volumio[1301]: info: MPD Permissions set
Feb 14 21:55:45 volumio RoonBridge[1744]: Running
Feb 14 21:55:45 volumio volumio-remote-updater[737]: [2026-02-14 21:55:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771086341 101
Feb 14 21:55:45 volumio volumio[1301]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1
Feb 14 21:55:45 volumio volumio[1301]: info: Spotify config file written
Feb 14 21:55:45 volumio volumio[1301]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2
Feb 14 21:55:45 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:45 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:45 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:45 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:45 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:45 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:45 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:45 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:46 volumio sudo[1776]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Feb 14 21:55:46 volumio sudo[1776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 14 21:55:46 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 14 21:55:46 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 14 21:55:46 volumio go-librespot[1787]: go-librespot daemon starting...
Feb 14 21:55:46 volumio sudo[1776]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:55:46 volumio volumio[1301]: info: No need to fix Spotify hosts
Feb 14 21:55:46 volumio mpd[1701]: 2026-02-14T21:55:46 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 14 21:55:46 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Feb 14 21:55:46 volumio sudo[1681]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:46 volumio volumio[1301]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2
Feb 14 21:55:46 volumio sudo[1664]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:46 volumio volumio[1301]: info: Received Get System Info
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 14 21:55:46 volumio volumio[1301]: info: Discovery: Getting this device information
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState
Feb 14 21:55:46 volumio volumio[1301]: info: CorePlayQueue::getTrack 0
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 14 21:55:46 volumio volumio5-onboarding[1682]: time=2026-02-14T21:55:46.494+05:30 level=INFO msg="system info for 7040afa019d30d2bbf52e9272b77ac6b" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.096
Feb 14 21:55:46 volumio volumio[1301]: info: Completed starting Core Plugins
Feb 14 21:55:46 volumio volumio[1301]: info: -------------------------------------------
Feb 14 21:55:46 volumio volumio[1301]: info: ----- MyVolumio plugins startup ----
Feb 14 21:55:46 volumio volumio[1301]: info: -------------------------------------------
Feb 14 21:55:46 volumio volumio[1301]: info: [MyVolumio PluginManager] Fetching plans data....
Feb 14 21:55:46 volumio go-librespot[1790]: time="2026-02-14T21:55:46+05:30" level=info msg="running go-librespot 0.6.2"
Feb 14 21:55:46 volumio go-librespot[1790]: time="2026-02-14T21:55:46+05:30" level=debug msg="app state loaded"
Feb 14 21:55:46 volumio go-librespot[1790]: time="2026-02-14T21:55:46+05:30" level=debug msg="stored credentials not found"
Feb 14 21:55:46 volumio go-librespot[1790]: time="2026-02-14T21:55:46+05:30" level=info msg="api server listening on 127.0.0.1:9879"
Feb 14 21:55:46 volumio volumio[1301]: info: Received Get System Info
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 14 21:55:46 volumio volumio[1301]: info: Discovery: Getting this device information
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState
Feb 14 21:55:46 volumio volumio[1301]: info: CorePlayQueue::getTrack 0
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 14 21:55:46 volumio volumio[1301]: info: Starting Shairport Sync
Feb 14 21:55:46 volumio volumio[1301]: info: Starting Shairport Sync
Feb 14 21:55:46 volumio volumio[1301]: info: Starting Shairport Sync
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 14 21:55:46 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 14 21:55:46 volumio sudo[1812]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 14 21:55:46 volumio sudo[1812]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 14 21:55:47 volumio sudo[1814]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 14 21:55:47 volumio sudo[1814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 14 21:55:47 volumio sudo[1816]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 14 21:55:47 volumio sudo[1816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 14 21:55:47 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 14 21:55:47 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 14 21:55:47 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 14 21:55:47 volumio systemd[1]: shairport-sync.service: Consumed 1.398s CPU time.
Feb 14 21:55:47 volumio RoonBridge[1758]: 00:00:00.075 Warn: get lock file path: /tmp/.rnbhgem0-
Feb 14 21:55:47 volumio volumio[1301]: error: MPD error: The expression evaluated to a falsy value:
Feb 14 21:55:47 volumio volumio[1301]: assert.ok(self.idling)
Feb 14 21:55:47 volumio volumio[1301]: error: The expression evaluated to a falsy value:
Feb 14 21:55:47 volumio volumio[1301]: assert.ok(self.idling)
Feb 14 21:55:47 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 14 21:55:47 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 14 21:55:47 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 14 21:55:47 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 14 21:55:47 volumio sudo[1812]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:47 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 14 21:55:47 volumio sudo[1814]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:47 volumio sudo[1816]: pam_unix(sudo:session): session closed for user root
Feb 14 21:55:47 volumio volumio[1301]: error: updateQueue error: null
Feb 14 21:55:47 volumio volumio[1301]: info: Shairport-Sync Started
Feb 14 21:55:47 volumio volumio[1301]: Error adding Membership: Error: addMembership EINVAL
Feb 14 21:55:47 volumio volumio[1301]: info: Shairport-Sync Started
Feb 14 21:55:47 volumio volumio[1301]: info: Shairport-Sync Started
Feb 14 21:55:47 volumio volumio[1301]: info: MPD running with PID1701
Feb 14 21:55:47 volumio volumio[1301]: ,establishing connection
Feb 14 21:55:47 volumio volumio[1301]: error: updateQueue error: null
Feb 14 21:55:47 volumio volumio[1301]: info: AutoStart - Check #2/60 - VOLUMIO_SYSTEM_STATUS = starting
Feb 14 21:55:48 volumio upmpdcli[1848]: writing RSA key
Feb 14 21:55:49 volumio volumio[1301]: info: go-librespot daemon successfully initialized
Feb 14 21:55:51 volumio volumio5-onboarding[1682]: time=2026-02-14T21:55:51.994+05:30 level=INFO msg="enabling local network discovery"
Feb 14 21:55:52 volumio volumio5-onboarding[1682]: time=2026-02-14T21:55:52.021+05:30 level=INFO msg="enabling BLE discovery"
Feb 14 21:55:52 volumio volumio[1301]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Feb 14 21:55:52 volumio ntpd[1061]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101
Feb 14 21:55:52 volumio ntpd[1061]: DNS: dns_check: DNS error: -11, System error
Feb 14 21:55:52 volumio ntpd[1061]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12
Feb 14 21:55:52 volumio volumio[1301]: info: AutoStart - Check #3/60 - VOLUMIO_SYSTEM_STATUS = starting
Feb 14 21:55:52 volumio volumio[1301]: info: Initializing connection to go-librespot Websocket
Feb 14 21:55:53 volumio ntpd[1061]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101
Feb 14 21:55:54 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0.
Feb 14 21:55:54 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0...
Feb 14 21:55:54 volumio systemd[1]: welcome.service: Deactivated successfully.
Feb 14 21:55:54 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console.
Feb 14 21:55:54 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console...
Feb 14 21:55:54 volumio systemd[1]: Starting welcome.service - Show a welcome message on console...
Feb 14 21:55:54 volumio welcome[1913]: Resolved ip:[1] 192.168.1.36
Feb 14 21:55:54 volumio systemd[1]: Finished welcome.service - Show a welcome message on console.
Feb 14 21:55:54 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0.
Feb 14 21:55:54 volumio volumio[1301]: info: Received Get System Info
Feb 14 21:55:54 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 14 21:55:54 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 14 21:55:54 volumio volumio[1301]: info: Discovery: Getting this device information
Feb 14 21:55:54 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState
Feb 14 21:55:54 volumio volumio[1301]: info: CorePlayQueue::getTrack 0
Feb 14 21:55:54 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 14 21:55:54 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 14 21:55:54 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 14 21:55:57 volumio volumio5-onboarding[1682]: time=2026-02-14T21:55:57.053+05:30 level=INFO msg="bootstrapping state" hasInternet=false
Feb 14 21:55:57 volumio volumio[1301]: info: AutoStart - Check #4/60 - VOLUMIO_SYSTEM_STATUS = starting
Feb 14 21:56:02 volumio volumio[1301]: info: AutoStart - Check #5/60 - VOLUMIO_SYSTEM_STATUS = starting
Feb 14 21:56:06 volumio volumio[1301]: error: MyVolumio Plugin failed to start in a timely fashion
Feb 14 21:56:06 volumio volumio[1301]: [Metrics] CommandRouter: 43s 493.20ms
Feb 14 21:56:06 volumio volumio[1301]: info: CoreCommandRouter::volumiosetStartupVolume
Feb 14 21:56:06 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 14 21:56:06 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 14 21:56:06 volumio volumio[1301]: info: CoreCommandRouter::Close All Modals sent
Feb 14 21:56:06 volumio volumio[1301]: info: CoreCommandRouter::Close All Modals sent
Feb 14 21:56:07 volumio volumio[1301]: info: AutoStart - Check #6/60 - VOLUMIO_SYSTEM_STATUS = starting
Feb 14 21:56:07 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Feb 14 21:56:07 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 14 21:56:07 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Feb 14 21:56:09 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0.
Feb 14 21:56:09 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0...
Feb 14 21:56:09 volumio systemd[1]: welcome.service: Deactivated successfully.
Feb 14 21:56:09 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console.
Feb 14 21:56:09 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console...
Feb 14 21:56:09 volumio systemd[1]: Starting welcome.service - Show a welcome message on console...
Feb 14 21:56:09 volumio welcome[1977]: Resolved ip:[1] 192.168.1.36
Feb 14 21:56:09 volumio systemd[1]: Finished welcome.service - Show a welcome message on console.
Feb 14 21:56:09 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0.
Feb 14 21:56:09 volumio volumio[1301]: info: Received Get System Info
Feb 14 21:56:09 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 14 21:56:09 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 14 21:56:09 volumio volumio[1301]: info: Discovery: Getting this device information
Feb 14 21:56:09 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState
Feb 14 21:56:09 volumio volumio[1301]: info: CorePlayQueue::getTrack 0
Feb 14 21:56:09 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 14 21:56:09 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 14 21:56:09 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 14 21:56:10 volumio volumio[1301]: info: Discovery: adding 54b545ac-9715-4114-afc2-148b184fa006
Feb 14 21:56:10 volumio volumio[1301]: info: Discovery: Found device Volumio
Feb 14 21:56:10 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState
Feb 14 21:56:10 volumio volumio[1301]: info: CorePlayQueue::getTrack 0
Feb 14 21:56:10 volumio volumio[1301]: info: Discovery: this is already registered, 54b545ac-9715-4114-afc2-148b184fa006
Feb 14 21:56:10 volumio volumio[1301]: info: Discovery: Found device Volumio
Feb 14 21:56:10 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState
Feb 14 21:56:10 volumio volumio[1301]: info: CorePlayQueue::getTrack 0
Feb 14 21:56:10 volumio volumio[1301]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Feb 14 21:56:12 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 14 21:56:12 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 14 21:56:12 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 14 21:56:12 volumio volumio[1301]: info: AutoStart - Check #7/60 - VOLUMIO_SYSTEM_STATUS = starting
Feb 14 21:56:13 volumio volumio[1301]: info: BOOT COMPLETED
Feb 14 21:56:14 volumio bash[1612]: setdatetime-helper: all HTTPS Date fallbacks failed
Feb 14 21:56:14 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully.
Feb 14 21:56:14 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Feb 14 21:56:16 volumio go-librespot[1790]: time="2026-02-14T21:56:16+05:30" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: i/o timeout (Client.Timeout exceeded while awaiting headers)"
Feb 14 21:56:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 14 21:56:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 14 21:56:17 volumio volumio[1301]: info: AutoStart - Check #8/60 - VOLUMIO_SYSTEM_STATUS = ready
Feb 14 21:56:17 volumio volumio[1301]: info: AutoStart - System ready state CONFIRMED after 8 checks
Feb 14 21:56:17 volumio volumio[1301]: info: AutoStart - Applying additional delay of 5000ms before playback
Feb 14 21:56:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1.
Feb 14 21:56:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 14 21:56:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 14 21:56:19 volumio go-librespot[2013]: go-librespot daemon starting...
Feb 14 21:56:20 volumio go-librespot[2015]: time="2026-02-14T21:56:19+05:30" level=info msg="running go-librespot 0.6.2"
Feb 14 21:56:20 volumio go-librespot[2015]: time="2026-02-14T21:56:20+05:30" level=debug msg="app state loaded"
Feb 14 21:56:20 volumio go-librespot[2015]: time="2026-02-14T21:56:20+05:30" level=debug msg="stored credentials not found"
Feb 14 21:56:20 volumio go-librespot[2015]: time="2026-02-14T21:56:20+05:30" level=info msg="api server listening on 127.0.0.1:9879"
Feb 14 21:56:22 volumio volumio[1301]: info: AutoStart - startPlayback called
Feb 14 21:56:22 volumio volumio[1301]: info: CoreCommandRouter::volumioGetQueue
Feb 14 21:56:22 volumio volumio[1301]: info: CoreStateMachine::getQueue
Feb 14 21:56:22 volumio volumio[1301]: info: CorePlayQueue::getQueue
Feb 14 21:56:22 volumio volumio[1301]: info: AutoStart - Queue has 1 items
Feb 14 21:56:22 volumio volumio[1301]: info: AutoStart - Playing from position 0
Feb 14 21:56:22 volumio volumio[1301]: info: CoreCommandRouter::volumioPlay
Feb 14 21:56:22 volumio volumio[1301]: info: CoreStateMachine::play index 0
Feb 14 21:56:22 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 14 21:56:22 volumio volumio[1301]: info: CoreStateMachine::stop
Feb 14 21:56:22 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 14 21:56:22 volumio volumio[1301]: info: CoreStateMachine::play index undefined
Feb 14 21:56:22 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 14 21:56:22 volumio volumio[1301]: info: CorePlayQueue::getTrack 0
Feb 14 21:56:22 volumio volumio[1301]: info: CoreStateMachine::startPlaybackTimer
Feb 14 21:56:22 volumio volumio[1301]: info: CorePlayQueue::getTrack 0
Feb 14 21:56:22 volumio volumio[1301]: info: [1771086382625] ControllerWebradio::clearAddPlayTrack
Feb 14 21:56:22 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand stop
Feb 14 21:56:22 volumio volumio[1301]: info: sendMpdCommand stop took 2 milliseconds
Feb 14 21:56:22 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand clear
Feb 14 21:56:22 volumio volumio[1301]: info:
Feb 14 21:56:22 volumio volumio[1301]: ---------------------------- MPD announces system playlist update
Feb 14 21:56:22 volumio volumio[1301]: info: Ignoring MPD Status Update
Feb 14 21:56:22 volumio volumio[1301]: info: sendMpdCommand clear took 3 milliseconds
Feb 14 21:56:22 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99581280"
Feb 14 21:56:22 volumio volumio[1301]: info:
Feb 14 21:56:22 volumio volumio[1301]: ---------------------------- MPD announces system playlist update
Feb 14 21:56:22 volumio volumio[1301]: info: Ignoring MPD Status Update
Feb 14 21:56:22 volumio volumio[1301]: info:
Feb 14 21:56:22 volumio volumio[1301]: ---------------------------- MPD announces system playlist update
Feb 14 21:56:22 volumio volumio[1301]: info: Ignoring MPD Status Update
Feb 14 21:56:22 volumio volumio[1301]: error: updateQueue error: null
Feb 14 21:56:22 volumio volumio[1301]: info: ------------------------------ 67ms
Feb 14 21:56:23 volumio ntpd[1061]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101
Feb 14 21:56:23 volumio ntpd[1061]: DNS: dns_check: DNS error: -11, System error
Feb 14 21:56:23 volumio ntpd[1061]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12
Feb 14 21:56:24 volumio ntpd[1061]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101
Feb 14 21:56:24 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0.
Feb 14 21:56:24 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0...
Feb 14 21:56:24 volumio systemd[1]: welcome.service: Deactivated successfully.
Feb 14 21:56:24 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console.
Feb 14 21:56:24 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console...
Feb 14 21:56:24 volumio systemd[1]: Starting welcome.service - Show a welcome message on console...
Feb 14 21:56:24 volumio welcome[2039]: Resolved ip:[1] 192.168.1.36
Feb 14 21:56:24 volumio systemd[1]: Finished welcome.service - Show a welcome message on console.
Feb 14 21:56:24 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0.
Feb 14 21:56:24 volumio volumio[1301]: info: Received Get System Info
Feb 14 21:56:24 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 14 21:56:24 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 14 21:56:24 volumio volumio[1301]: info: Discovery: Getting this device information
Feb 14 21:56:24 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState
Feb 14 21:56:24 volumio volumio[1301]: info: CorePlayQueue::getTrack 0
Feb 14 21:56:24 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 14 21:56:24 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 14 21:56:24 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 14 21:56:25 volumio volumio[1301]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.096&uuid=7040afa019d30d2bbf52e9272b77ac6b" http://updates.volumio.org/downloader-v1/track-device
Feb 14 21:56:25 volumio volumio[1301]: % Total % Received % Xferd Average Speed Time Time Time Current
Feb 14 21:56:25 volumio volumio[1301]: Dload Upload Total Spent Left Speed
Feb 14 21:56:25 volumio volumio[1301]: [2.5K blob data]
Feb 14 21:56:25 volumio volumio[1301]: retrying in 5 seconds, trial 0
Feb 14 21:56:25 volumio volumio[1301]: info: Volumio Calling Home
Feb 14 21:56:36 volumio systemd[1]: mpd.service: Watchdog timeout (limit 20s)!
Feb 14 21:56:36 volumio systemd[1]: mpd.service: Killing process 1701 (mpd) with signal SIGABRT.
Feb 14 21:56:36 volumio upmpdcli[1643]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Feb 14 21:56:36 volumio volumio[1301]: error: MPD error: Error: read ECONNRESET
Feb 14 21:56:36 volumio volumio[1301]: error: MPD error: Error: read ECONNRESET
Feb 14 21:56:36 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=6/ABRT
Feb 14 21:56:36 volumio systemd[1]: mpd.service: Failed with result 'watchdog'.
Feb 14 21:56:36 volumio systemd[1]: mpd.service: Consumed 3.030s CPU time.
Feb 14 21:56:36 volumio volumio[1301]: error: MPD error: Error: read ECONNRESET
Feb 14 21:56:37 volumio ntpd[1061]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101
Feb 14 21:56:37 volumio ntpd[1061]: DNS: dns_check: DNS error: -11, System error
Feb 14 21:56:37 volumio ntpd[1061]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12
Feb 14 21:56:37 volumio go-librespot[2015]: time="2026-02-14T21:56:37+05:30" level=debug msg="new websocket client"
Feb 14 21:56:37 volumio volumio[1301]: info: Connection to go-librespot Websocket established
Feb 14 21:56:37 volumio volumio[1301]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Feb 14 21:56:37 volumio volumio[1301]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Feb 14 21:56:38 volumio go-librespot[2015]: time="2026-02-14T21:56:38+05:30" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy"
Feb 14 21:56:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 14 21:56:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 14 21:56:38 volumio volumio[1301]: info: Connection to go-librespot Websocket closed
Feb 14 21:56:38 volumio ntpd[1061]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101
Feb 14 21:56:38 volumio ntpd[1061]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101
Feb 14 21:56:38 volumio ntpd[1061]: DNS: Pool taking: 103.136.36.100
Feb 14 21:56:38 volumio ntpd[1061]: DNS: Pool taking: 162.159.200.123
Feb 14 21:56:38 volumio ntpd[1061]: DNS: Pool taking: 13.200.20.166
Feb 14 21:56:38 volumio ntpd[1061]: DNS: Pool taking: 17.253.18.131
Feb 14 21:56:38 volumio ntpd[1061]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8
Feb 14 21:56:38 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState
Feb 14 21:56:38 volumio volumio[1301]: info: CorePlayQueue::getTrack 0
Feb 14 21:56:38 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 14 21:56:38 volumio sudo[2075]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 14 21:56:38 volumio sudo[2075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 14 21:56:38 volumio sudo[2075]: pam_unix(sudo:session): session closed for user root
Feb 14 21:56:39 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0.
Feb 14 21:56:39 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0...
Feb 14 21:56:39 volumio systemd[1]: welcome.service: Deactivated successfully.
Feb 14 21:56:39 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console.
Feb 14 21:56:39 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console...
Feb 14 21:56:39 volumio systemd[1]: Starting welcome.service - Show a welcome message on console...
Feb 14 21:56:39 volumio welcome[2108]: Resolved ip:[1] 192.168.1.36
Feb 14 21:56:39 volumio systemd[1]: Finished welcome.service - Show a welcome message on console.
Feb 14 21:56:39 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0.
Feb 14 21:56:39 volumio volumio[1301]: info: Received Get System Info
Feb 14 21:56:39 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 14 21:56:39 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 14 21:56:39 volumio volumio[1301]: info: Discovery: Getting this device information
Feb 14 21:56:39 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState
Feb 14 21:56:39 volumio volumio[1301]: info: CorePlayQueue::getTrack 0
Feb 14 21:56:39 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 14 21:56:39 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 14 21:56:39 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 14 21:56:40 volumio volumio5-onboarding[1682]: time=2026-02-14T21:56:40.042+05:30 level=INFO msg="disabling BLE discovery"
Feb 14 21:56:40 volumio volumio5-onboarding[1682]: time=2026-02-14T21:56:40.086+05:30 level=INFO msg="connection closed, stopping read loop" component=conn/multi net=ble addr=D8:3A:DD:C2:75:9D%00
Feb 14 21:56:40 volumio mpd[2077]: 2026-02-14T21:56:40 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 14 21:56:40 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Feb 14 21:56:40 volumio upmpdcli[1643]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started
Feb 14 21:56:40 volumio volumio[1301]: info: Getting Spotify volume
Feb 14 21:56:40 volumio volumio[1301]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 14 21:56:40 volumio volumio[1301]: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 14 21:56:40 volumio volumio[1301]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Feb 14 21:56:40 volumio volumio[1301]: errno: -111,
Feb 14 21:56:40 volumio volumio[1301]: code: 'ECONNREFUSED',
Feb 14 21:56:40 volumio volumio[1301]: syscall: 'connect',
Feb 14 21:56:40 volumio volumio[1301]: address: '127.0.0.1',
Feb 14 21:56:40 volumio volumio[1301]: port: 9879,
Feb 14 21:56:40 volumio volumio[1301]: response: undefined
Feb 14 21:56:40 volumio volumio[1301]: }
Feb 14 21:56:40 volumio volumio[1301]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 14 21:56:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2.
Feb 14 21:56:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 14 21:56:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 14 21:56:41 volumio go-librespot[2135]: go-librespot daemon starting...
Feb 14 21:56:41 volumio go-librespot[2136]: time="2026-02-14T21:56:41+05:30" level=info msg="running go-librespot 0.6.2"
Feb 14 21:56:41 volumio go-librespot[2136]: time="2026-02-14T21:56:41+05:30" level=debug msg="app state loaded"
Feb 14 21:56:41 volumio go-librespot[2136]: time="2026-02-14T21:56:41+05:30" level=debug msg="stored credentials not found"
Feb 14 21:56:41 volumio go-librespot[2136]: time="2026-02-14T21:56:41+05:30" level=info msg="api server listening on 127.0.0.1:9879"
Feb 14 21:56:41 volumio go-librespot[2136]: time="2026-02-14T21:56:41+05:30" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 14 21:56:41 volumio go-librespot[2136]: time="2026-02-14T21:56:41+05:30" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 14 21:56:41 volumio go-librespot[2136]: time="2026-02-14T21:56:41+05:30" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 14 21:56:41 volumio go-librespot[2136]: time="2026-02-14T21:56:41+05:30" level=info msg="zeroconf server listening on port 38545"
Feb 14 21:56:43 volumio sudo[2148]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-14 21:55'
Feb 14 21:56:43 volumio sudo[2148]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026"
VOLUMIO_VERSION="4.096"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"