Feb 07 19:11:22 volumio ntpd[1158]: CLOCK: time stepped by 5524130.057001 Feb 07 19:11:22 volumio ntpd[1158]: CLOCK: time changed from 2025-12-05 to 2026-02-07 Feb 07 19:11:22 volumio ntpd[1158]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Feb 07 19:11:22 volumio volumio[1335]: info: Discovery: A device disappeared from network Feb 07 19:11:22 volumio volumio[1335]: info: Discovery: Device volumio disappeared from network Feb 07 19:11:22 volumio volumio[1335]: info: Discovery: A device disappeared from network Feb 07 19:11:22 volumio volumio[1335]: info: Discovery: A device disappeared from network Feb 07 19:11:22 volumio dhcpcd[1019]: wlan0: carrier lost Feb 07 19:11:22 volumio systemd[1]: hostapd.service: Deactivated successfully. Feb 07 19:11:22 volumio systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Feb 07 19:11:22 volumio sudo[1971]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Feb 07 19:11:22 volumio sudo[1971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 19:11:22 volumio avahi-daemon[969]: Withdrawing address record for 192.168.211.1 on wlan0. Feb 07 19:11:22 volumio kernel: ieee80211 phy0: brcmf_escan_timeout: timer expired Feb 07 19:11:22 volumio avahi-daemon[969]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Feb 07 19:11:22 volumio avahi-daemon[969]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 07 19:11:22 volumio sudo[1971]: pam_unix(sudo:session): session closed for user root Feb 07 19:11:22 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Feb 07 19:11:22 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Feb 07 19:11:22 volumio sudo[1977]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Feb 07 19:11:22 volumio sudo[1977]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 19:11:22 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Feb 07 19:11:22 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Feb 07 19:11:22 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Feb 07 19:11:22 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 07 19:11:22 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Feb 07 19:11:22 volumio systemd[1]: welcome.service: Deactivated successfully. Feb 07 19:11:22 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 07 19:11:22 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 07 19:11:22 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 07 19:11:22 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Feb 07 19:11:22 volumio firststart.sh[1967]: rescue-ssh.target is a disabled or a static unit not running, not starting it. Feb 07 19:11:22 volumio firststart.sh[1967]: ssh.service is a disabled or a static unit not running, not starting it. Feb 07 19:11:22 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Feb 07 19:11:22 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Feb 07 19:11:22 volumio systemd[1]: Reloading. Feb 07 19:11:22 volumio volumio[1335]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 07 19:11:22 volumio volumio[1335]: info: CoreStateMachine::getcurrentVolume Feb 07 19:11:22 volumio volumio[1335]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 19:11:22 volumio volumio[1335]: info: CoreStateMachine::pushState Feb 07 19:11:22 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Feb 07 19:11:22 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Feb 07 19:11:23 volumio welcome[1994]: Resolved ip:[1] 192.168.68.52 Feb 07 19:11:23 volumio sudo[1977]: pam_unix(sudo:session): session closed for user root Feb 07 19:11:23 volumio sudo[1766]: pam_unix(sudo:session): session closed for user root Feb 07 19:11:23 volumio sudo[1755]: pam_unix(sudo:session): session closed for user root Feb 07 19:11:23 volumio volumio[1335]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Feb 07 19:11:23 volumio volumio[1335]: Terminated Feb 07 19:11:23 volumio volumio[1335]: info: Completed starting Core Plugins Feb 07 19:11:23 volumio volumio[1335]: info: ------------------------------------------- Feb 07 19:11:23 volumio volumio[1335]: info: ----- MyVolumio plugins startup ---- Feb 07 19:11:23 volumio volumio[1335]: info: ------------------------------------------- Feb 07 19:11:23 volumio volumio[1335]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 07 19:11:23 volumio volumio[1335]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Feb 07 19:11:23 volumio volumio[1335]: Terminated Feb 07 19:11:23 volumio wireless.js[1897]: WIRELESS.JS: Stopped aP Feb 07 19:11:23 volumio sudo[2074]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 07 19:11:23 volumio sudo[2074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:11:23 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 07 19:11:23 volumio sudo[2074]: pam_unix(sudo:session): session closed for user root Feb 07 19:11:23 volumio sudo[2076]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 07 19:11:23 volumio sudo[2076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:11:23 volumio sudo[2076]: pam_unix(sudo:session): session closed for user root Feb 07 19:11:23 volumio sudo[2084]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 07 19:11:23 volumio sudo[2084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:11:23 volumio sudo[2084]: pam_unix(sudo:session): session closed for user root Feb 07 19:11:23 volumio sudo[2086]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 07 19:11:23 volumio sudo[2086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:11:23 volumio mpd[1794]: 2026-02-07T19:11:23 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 19:11:23 volumio mpd[1794]: 2026-02-07T19:11:23 exception: Database corrupted Feb 07 19:11:23 volumio volumio[1335]: error: MPD error: The expression evaluated to a falsy value: Feb 07 19:11:23 volumio volumio[1335]: assert.ok(self.idling) Feb 07 19:11:23 volumio volumio[1335]: error: The expression evaluated to a falsy value: Feb 07 19:11:23 volumio volumio[1335]: assert.ok(self.idling) Feb 07 19:11:23 volumio volumio[1335]: verbose: ControllerMpd::sendMpdCommand status Feb 07 19:11:23 volumio volumio[1335]: info: Feb 07 19:11:23 volumio volumio[1335]: ---------------------------- MPD announces state update: update Feb 07 19:11:23 volumio volumio[1335]: info: ControllerMpd::getState Feb 07 19:11:23 volumio volumio[1335]: verbose: ControllerMpd::sendMpdCommand status Feb 07 19:11:23 volumio volumio[1335]: verbose: ControllerMpd::sendMpdCommand status Feb 07 19:11:23 volumio volumio[1335]: info: Feb 07 19:11:23 volumio volumio[1335]: ---------------------------- MPD announces state update: update Feb 07 19:11:23 volumio volumio[1335]: error: updateQueue error: null Feb 07 19:11:23 volumio volumio[1335]: info: ControllerMpd::getState Feb 07 19:11:23 volumio volumio[1335]: verbose: ControllerMpd::sendMpdCommand status Feb 07 19:11:23 volumio volumio[1335]: info: sendMpdCommand status took 5 milliseconds Feb 07 19:11:23 volumio volumio[1335]: verbose: ControllerMpd::parseState Feb 07 19:11:23 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 07 19:11:23 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 07 19:11:23 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 07 19:11:23 volumio firststart.sh[2097]: ssh.socket is a disabled or a static unit not running, not starting it. Feb 07 19:11:23 volumio firststart.sh[1336]: Enabling SSH for first boot Feb 07 19:11:23 volumio volumio[1335]: info: Command Router : Notfying DB Updatefalse Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::Close All Modals sent Feb 07 19:11:23 volumio volumio[1335]: info: sendMpdCommand status took 593 milliseconds Feb 07 19:11:23 volumio volumio[1335]: info: sendMpdCommand status took 593 milliseconds Feb 07 19:11:23 volumio volumio[1335]: info: sendMpdCommand status took 592 milliseconds Feb 07 19:11:23 volumio volumio[1335]: verbose: ControllerMpd::parseState Feb 07 19:11:23 volumio volumio[1335]: verbose: ControllerMpd::parseState Feb 07 19:11:23 volumio volumio[1335]: info: Command Router : Notfying DB Updatefalse Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::Close All Modals sent Feb 07 19:11:23 volumio volumio[1335]: verbose: ControllerMpd::parseState Feb 07 19:11:23 volumio volumio[1335]: info: ControllerMpd::pushState Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::servicePushState Feb 07 19:11:23 volumio volumio[1335]: info: CoreStateMachine::pushState Feb 07 19:11:23 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Feb 07 19:11:23 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Feb 07 19:11:23 volumio volumio[1335]: 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} Feb 07 19:11:23 volumio volumio[1335]: verbose: CURRENT POSITION 0 Feb 07 19:11:23 volumio volumio[1335]: info: CoreStateMachine::syncState stateService stop Feb 07 19:11:23 volumio volumio[1335]: info: CoreStateMachine::syncState currentStatus stop Feb 07 19:11:23 volumio volumio[1335]: info: CoreStateMachine::pushState Feb 07 19:11:23 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Feb 07 19:11:23 volumio volumio[1335]: info: No code Feb 07 19:11:23 volumio volumio[1335]: info: CoreStateMachine::pushState Feb 07 19:11:23 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Feb 07 19:11:23 volumio volumio[1335]: info: ControllerMpd::pushState Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::servicePushState Feb 07 19:11:23 volumio volumio[1335]: info: CoreStateMachine::pushState Feb 07 19:11:23 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Feb 07 19:11:23 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Feb 07 19:11:23 volumio volumio[1335]: 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} Feb 07 19:11:23 volumio volumio[1335]: verbose: CURRENT POSITION 0 Feb 07 19:11:23 volumio volumio[1335]: info: CoreStateMachine::syncState stateService stop Feb 07 19:11:23 volumio volumio[1335]: info: CoreStateMachine::syncState currentStatus stop Feb 07 19:11:23 volumio volumio[1335]: info: CoreStateMachine::pushState Feb 07 19:11:23 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Feb 07 19:11:23 volumio volumio[1335]: info: No code Feb 07 19:11:23 volumio volumio[1335]: info: CoreStateMachine::pushState Feb 07 19:11:23 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Feb 07 19:11:23 volumio volumio[1335]: info: ------------------------------ 635ms Feb 07 19:11:23 volumio systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... Feb 07 19:11:23 volumio volumio[1335]: info: ------------------------------ 637ms Feb 07 19:11:23 volumio volumio[1335]: verbose: New Socket.io Connection to 192.168.68.52 from 192.168.68.56 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::volumioGetVisibleSources Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Feb 07 19:11:23 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Feb 07 19:11:23 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 07 19:11:23 volumio volumio[1335]: info: Received Get System Info Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 19:11:23 volumio volumio[1335]: info: Discovery: Getting this device information Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Feb 07 19:11:23 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 19:11:23 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Feb 07 19:11:23 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Feb 07 19:11:23 volumio volumio[1335]: info: Listing playlists Feb 07 19:11:23 volumio sshd[2108]: Server listening on 0.0.0.0 port 22. Feb 07 19:11:23 volumio sshd[2108]: Server listening on :: port 22. Feb 07 19:11:23 volumio systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Feb 07 19:11:23 volumio firststart.sh[1336]: Disabling firststart service Feb 07 19:11:23 volumio systemctl[2109]: Removed "/etc/systemd/system/multi-user.target.wants/firststart.service". Feb 07 19:11:23 volumio systemd[1]: Reloading. Feb 07 19:11:24 volumio firststart.sh[1336]: Finalizing Feb 07 19:11:24 volumio systemd[1]: firststart.service: Deactivated successfully. Feb 07 19:11:24 volumio systemd[1]: firststart.service: Consumed 4.101s CPU time. Feb 07 19:11:25 volumio ntpd[1158]: IO: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=9 secs Feb 07 19:11:25 volumio sudo[2086]: pam_unix(sudo:session): session closed for user root Feb 07 19:11:25 volumio wireless.js[1897]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: DE Feb 07 19:11:25 volumio sudo[2130]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 07 19:11:25 volumio sudo[2130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:11:25 volumio sudo[2130]: pam_unix(sudo:session): session closed for user root Feb 07 19:11:25 volumio sudo[2132]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Feb 07 19:11:25 volumio sudo[2132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:11:25 volumio sudo[2132]: pam_unix(sudo:session): session closed for user root Feb 07 19:11:25 volumio wireless.js[1897]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: DE Feb 07 19:11:25 volumio wireless.js[1897]: WIRELESS.JS: Single Network Mode: Wired network active, not starting wireless flow Feb 07 19:11:25 volumio wireless.js[1897]: WIRELESS.JS: Notified systemd about wireless ready Feb 07 19:11:25 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 07 19:11:25 volumio systemd[1]: Started wireless.service - Wireless Services. Feb 07 19:11:25 volumio sudo[1875]: pam_unix(sudo:session): session closed for user root Feb 07 19:11:26 volumio sudo[2140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 07 19:11:26 volumio sudo[2140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:11:26 volumio sudo[2143]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 07 19:11:26 volumio sudo[2143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:11:26 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 07 19:11:26 volumio sudo[2140]: pam_unix(sudo:session): session closed for user root Feb 07 19:11:26 volumio mpd_monitor.sh[2145]: MPD Monitor Service: Starting MPD Monitor Service Feb 07 19:11:26 volumio volumio[1335]: info: Successfully started MPD Monitor Feb 07 19:11:26 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 07 19:11:26 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 07 19:11:26 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 07 19:11:26 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 07 19:11:26 volumio sudo[2143]: pam_unix(sudo:session): session closed for user root Feb 07 19:11:26 volumio mpd_monitor.sh[2147]: MPD Monitor Service: Starting MPD Monitor Service Feb 07 19:11:26 volumio volumio[1335]: info: Successfully started MPD Monitor Feb 07 19:11:26 volumio volumio[1335]: info: Discovery: Started advertising with name: Volumio Feb 07 19:11:27 volumio volumio[1335]: compat.c: read() failed: Resource temporarily unavailable Feb 07 19:11:27 volumio volumio[1335]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 19:11:27 volumio volumio[1335]: Error: dns service error: unknown Feb 07 19:11:27 volumio volumio[1335]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Feb 07 19:11:27 volumio volumio[1335]: errorCode: -65537 Feb 07 19:11:27 volumio volumio[1335]: } Feb 07 19:11:27 volumio volumio[1335]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 19:11:27 volumio sudo[2168]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-07 19:10' Feb 07 19:11:27 volumio sudo[2168]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"