Apr 14 16:39:01 volumiopi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 76. Apr 14 16:39:01 volumiopi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 16:39:01 volumiopi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 16:39:01 volumiopi upmpdcli[4422]: Could not open config: /tmp/upmpdcli.conf Apr 14 16:39:01 volumiopi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 14 16:39:01 volumiopi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 14 16:39:16 volumiopi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 77. Apr 14 16:39:16 volumiopi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 16:39:16 volumiopi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 16:39:16 volumiopi upmpdcli[4451]: Could not open config: /tmp/upmpdcli.conf Apr 14 16:39:16 volumiopi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 14 16:39:16 volumiopi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 14 16:39:31 volumiopi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 78. Apr 14 16:39:31 volumiopi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 16:39:31 volumiopi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 16:39:31 volumiopi upmpdcli[4467]: Could not open config: /tmp/upmpdcli.conf Apr 14 16:39:31 volumiopi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 14 16:39:31 volumiopi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 14 16:39:47 volumiopi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 79. Apr 14 16:39:47 volumiopi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 16:39:47 volumiopi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 16:39:47 volumiopi upmpdcli[4497]: Could not open config: /tmp/upmpdcli.conf Apr 14 16:39:47 volumiopi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 14 16:39:47 volumiopi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 14 16:39:55 volumiopi kernel: hwmon hwmon1: Undervoltage detected! Apr 14 16:39:56 volumiopi wpa_supplicant[1195]: wlan0: CTRL-EVENT-DISCONNECTED bssid=fc:34:97:42:e3:a8 reason=2 Apr 14 16:39:56 volumiopi wpa_supplicant[1195]: BSSID fc:34:97:42:e3:a8 ignore list count incremented to 2, ignoring for 10 seconds Apr 14 16:39:56 volumiopi dhcpcd[861]: wlan0: carrier lost Apr 14 16:39:56 volumiopi wpa_supplicant[1195]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Apr 14 16:39:56 volumiopi wpa_supplicant[1195]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=GB Apr 14 16:39:56 volumiopi avahi-daemon[1454]: Withdrawing address record for 192.168.50.117 on wlan0. Apr 14 16:39:56 volumiopi avahi-daemon[1454]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.50.117. Apr 14 16:39:56 volumiopi avahi-daemon[1454]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 14 16:39:56 volumiopi dhcpcd[861]: wlan0: deleting route to 192.168.50.0/24 Apr 14 16:39:56 volumiopi dhcpcd[861]: wlan0: deleting default route via 192.168.50.1 Apr 14 16:39:56 volumiopi volumio[1231]: info: Discovery: A device disappeared from network Apr 14 16:39:56 volumiopi systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Apr 14 16:39:56 volumiopi systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Apr 14 16:39:56 volumiopi systemd[1]: welcome.service: Deactivated successfully. Apr 14 16:39:56 volumiopi systemd[1]: Stopped welcome.service - Show a welcome message on console. Apr 14 16:39:56 volumiopi systemd[1]: Stopping welcome.service - Show a welcome message on console... Apr 14 16:39:56 volumiopi systemd[1]: Starting welcome.service - Show a welcome message on console... Apr 14 16:39:56 volumiopi welcome[4538]: Resolved ip:[0] Apr 14 16:39:56 volumiopi systemd[1]: Finished welcome.service - Show a welcome message on console. Apr 14 16:39:56 volumiopi systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Apr 14 16:39:56 volumiopi wpa_supplicant[1195]: MBO: Disable MBO/OCE due to misbehaving AP not having enabled PMF Apr 14 16:39:56 volumiopi wpa_supplicant[1195]: wlan0: Trying to associate with fc:34:97:42:e3:a4 (SSID='BOGOFF' freq=5260 MHz) Apr 14 16:39:56 volumiopi wpa_supplicant[1195]: wlan0: Associated with fc:34:97:42:e3:a4 Apr 14 16:39:56 volumiopi wpa_supplicant[1195]: MBO: Disable MBO/OCE due to misbehaving AP not having enabled PMF Apr 14 16:39:56 volumiopi wpa_supplicant[1195]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 14 16:39:56 volumiopi wpa_supplicant[1195]: wlan0: WPA: Key negotiation completed with fc:34:97:42:e3:a4 [PTK=CCMP GTK=CCMP] Apr 14 16:39:56 volumiopi wpa_supplicant[1195]: wlan0: CTRL-EVENT-CONNECTED - Connection to fc:34:97:42:e3:a4 completed [id=0 id_str=] Apr 14 16:39:56 volumiopi dhcpcd[861]: wlan0: carrier acquired Apr 14 16:39:56 volumiopi dhcpcd[861]: wlan0: IAID 32:e1:53:e1 Apr 14 16:39:57 volumiopi dhcpcd[861]: wlan0: rebinding lease of 192.168.50.117 Apr 14 16:39:57 volumiopi volumio[1231]: info: Received Get System Info Apr 14 16:39:57 volumiopi volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 16:39:57 volumiopi volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 16:39:57 volumiopi volumio[1231]: info: Discovery: Getting this device information Apr 14 16:39:57 volumiopi volumio[1231]: info: CoreCommandRouter::volumioGetState Apr 14 16:39:57 volumiopi volumio[1231]: info: CorePlayQueue::getTrack 1 Apr 14 16:39:57 volumiopi volumio[1231]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 16:39:57 volumiopi volumio[1231]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 16:39:57 volumiopi volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 16:39:57 volumiopi dhcpcd[861]: wlan0: soliciting an IPv6 router Apr 14 16:39:58 volumiopi volumio5-onboarding[1584]: time=2026-04-14T16:39:58.162+01:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 14 16:39:58 volumiopi ntpd[1029]: IO: Deleting interface #14 wlan0, 192.168.50.117#123, interface stats: received=13, sent=13, dropped=1, active_time=78 secs Apr 14 16:39:58 volumiopi ntpd[1029]: PROTO: 193.57.144.50 unlink local addr 192.168.50.117 -> Apr 14 16:39:58 volumiopi ntpd[1029]: PROTO: 109.74.192.36 unlink local addr 192.168.50.117 -> Apr 14 16:39:58 volumiopi ntpd[1029]: PROTO: 185.103.119.60 unlink local addr 192.168.50.117 -> Apr 14 16:39:58 volumiopi ntpd[1029]: PROTO: 131.111.8.60 unlink local addr 192.168.50.117 -> Apr 14 16:39:58 volumiopi ntpd[1029]: PROTO: 162.159.200.1 unlink local addr 192.168.50.117 -> Apr 14 16:39:58 volumiopi ntpd[1029]: PROTO: 195.171.43.10 unlink local addr 192.168.50.117 -> Apr 14 16:39:58 volumiopi ntpd[1029]: PROTO: 85.121.48.64 unlink local addr 192.168.50.117 -> Apr 14 16:39:58 volumiopi ntpd[1029]: PROTO: 193.150.34.2 unlink local addr 192.168.50.117 -> Apr 14 16:39:58 volumiopi ntpd[1029]: PROTO: 178.215.228.24 unlink local addr 192.168.50.117 -> Apr 14 16:39:59 volumiopi volumio[1231]: info: CorePlayQueue::getTrack 1 Apr 14 16:39:59 volumiopi volumio[1231]: info: CorePlayQueue::getTrack 2 Apr 14 16:39:59 volumiopi volumio[1231]: info: Prefetching next song Apr 14 16:39:59 volumiopi volumio[1231]: info: DOING PREFETCH IN MPD Apr 14 16:39:59 volumiopi volumio[1231]: verbose: ControllerMpd::sendMpdCommand add "USB/FLAC_MUSIC/16-44-FLAC/Barclay.J.Harvest-Everybody is everybody else/03 - Paper Wings.flac" Apr 14 16:39:59 volumiopi volumio[1231]: info: Apr 14 16:39:59 volumiopi volumio[1231]: ---------------------------- MPD announces system playlist update Apr 14 16:39:59 volumiopi volumio[1231]: info: Ignoring MPD Status Update Apr 14 16:39:59 volumiopi volumio[1231]: info: sendMpdCommand add "USB/FLAC_MUSIC/16-44-FLAC/Barclay.J.Harvest-Everybody is everybody else/03 - Paper Wings.flac" took 3 milliseconds Apr 14 16:39:59 volumiopi volumio[1231]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 14 16:39:59 volumiopi volumio[1231]: info: ------------------------------ 3ms Apr 14 16:39:59 volumiopi volumio[1231]: info: Apr 14 16:39:59 volumiopi volumio[1231]: ---------------------------- MPD announces state update: options Apr 14 16:39:59 volumiopi volumio[1231]: info: sendMpdCommand consume 1 took 4 milliseconds Apr 14 16:39:59 volumiopi volumio[1231]: info: ControllerMpd::getState Apr 14 16:39:59 volumiopi volumio[1231]: verbose: ControllerMpd::sendMpdCommand status Apr 14 16:39:59 volumiopi volumio[1231]: info: sendMpdCommand status took 25 milliseconds Apr 14 16:39:59 volumiopi volumio[1231]: verbose: ControllerMpd::parseState Apr 14 16:39:59 volumiopi volumio[1231]: info: ControllerMpd::pushState Apr 14 16:39:59 volumiopi volumio[1231]: info: CoreCommandRouter::servicePushState Apr 14 16:39:59 volumiopi volumio[1231]: info: CoreStateMachine::pushState Apr 14 16:39:59 volumiopi volumio[1231]: info: CorePlayQueue::getTrack 1 Apr 14 16:39:59 volumiopi volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 16:39:59 volumiopi volumio[1231]: info: CoreCommandRouter::volumioPushState Apr 14 16:39:59 volumiopi volumio[1231]: info: CorePlayQueue::getTrack 1 Apr 14 16:39:59 volumiopi volumio[1231]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 14 16:39:59 volumiopi volumio[1231]: verbose: CURRENT POSITION 1 Apr 14 16:39:59 volumiopi volumio[1231]: info: CoreStateMachine::syncState stateService stop Apr 14 16:39:59 volumiopi volumio[1231]: info: CoreStateMachine::syncState currentStatus play Apr 14 16:39:59 volumiopi volumio[1231]: info: CoreStateMachine::pushState Apr 14 16:39:59 volumiopi volumio[1231]: info: CorePlayQueue::getTrack 2 Apr 14 16:39:59 volumiopi volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 16:39:59 volumiopi volumio[1231]: info: CoreCommandRouter::volumioPushState Apr 14 16:39:59 volumiopi volumio[1231]: info: CoreStateMachine::pushState Apr 14 16:39:59 volumiopi volumio[1231]: info: CorePlayQueue::getTrack 2 Apr 14 16:39:59 volumiopi volumio[1231]: info: CoreCommandRouter::volumioPushState Apr 14 16:39:59 volumiopi volumio[1231]: info: ------------------------------ 64ms Apr 14 16:40:01 volumiopi dhcpcd[861]: wlan0: probing address 192.168.50.117/24 Apr 14 16:40:02 volumiopi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 80. Apr 14 16:40:02 volumiopi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 16:40:02 volumiopi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 16:40:02 volumiopi upmpdcli[4547]: Could not open config: /tmp/upmpdcli.conf Apr 14 16:40:02 volumiopi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 14 16:40:02 volumiopi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 14 16:40:04 volumiopi kernel: hwmon hwmon1: Voltage normalised Apr 14 16:40:04 volumiopi volumio[1231]: info: Volumio Network Manager: Network status updated: 0 Apr 14 16:40:06 volumiopi dhcpcd[861]: wlan0: leased 192.168.50.117 for 86400 seconds Apr 14 16:40:06 volumiopi avahi-daemon[1454]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.50.117. Apr 14 16:40:06 volumiopi avahi-daemon[1454]: New relevant interface wlan0.IPv4 for mDNS. Apr 14 16:40:06 volumiopi avahi-daemon[1454]: Registering new address record for 192.168.50.117 on wlan0.IPv4. Apr 14 16:40:06 volumiopi dhcpcd[861]: wlan0: adding route to 192.168.50.0/24 Apr 14 16:40:06 volumiopi systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Apr 14 16:40:06 volumiopi systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Apr 14 16:40:06 volumiopi systemd[1]: welcome.service: Deactivated successfully. Apr 14 16:40:06 volumiopi systemd[1]: Stopped welcome.service - Show a welcome message on console. Apr 14 16:40:06 volumiopi systemd[1]: Stopping welcome.service - Show a welcome message on console... Apr 14 16:40:06 volumiopi dhcpcd[861]: wlan0: adding default route via 192.168.50.1 Apr 14 16:40:06 volumiopi systemd[1]: Starting welcome.service - Show a welcome message on console... Apr 14 16:40:06 volumiopi welcome[4578]: Resolved ip:[1] 192.168.50.117 Apr 14 16:40:06 volumiopi systemd[1]: Finished welcome.service - Show a welcome message on console. Apr 14 16:40:06 volumiopi systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Apr 14 16:40:06 volumiopi volumio[1231]: info: Received Get System Info Apr 14 16:40:06 volumiopi volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 16:40:06 volumiopi volumio[1231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 16:40:06 volumiopi volumio[1231]: info: Discovery: Getting this device information Apr 14 16:40:06 volumiopi volumio[1231]: info: CoreCommandRouter::volumioGetState Apr 14 16:40:06 volumiopi volumio[1231]: info: CorePlayQueue::getTrack 2 Apr 14 16:40:06 volumiopi volumio[1231]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 16:40:06 volumiopi volumio[1231]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 16:40:06 volumiopi volumio[1231]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 16:40:07 volumiopi volumio[1231]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 16:40:07 volumiopi volumio[1231]: Error: read ETIMEDOUT Apr 14 16:40:07 volumiopi volumio[1231]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:217:20) { Apr 14 16:40:07 volumiopi volumio[1231]: errno: -110, Apr 14 16:40:07 volumiopi volumio[1231]: code: 'ETIMEDOUT', Apr 14 16:40:07 volumiopi volumio[1231]: syscall: 'read' Apr 14 16:40:07 volumiopi volumio[1231]: } Apr 14 16:40:07 volumiopi volumio[1231]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 16:40:07 volumiopi volumio5-onboarding[1584]: time=2026-04-14T16:40:07.747+01:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 14 16:40:08 volumiopi ntpd[1029]: IO: Listen normally on 15 wlan0 192.168.50.117:123 Apr 14 16:40:08 volumiopi ntpd[1029]: IO: new interface(s) found: waking up resolver Apr 14 16:40:08 volumiopi kernel: hwmon hwmon1: Undervoltage detected! Apr 14 16:40:08 volumiopi sudo[4604]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-14 16:39' Apr 14 16:40:08 volumiopi sudo[4604]: 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"