Feb 23 22:24:04 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 23 22:24:04 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 23 22:24:04 volumio systemd[1]: setdatetime-helper.service: Consumed 1.805s CPU time. Feb 23 22:24:04 volumio sudo[1213]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 23 22:24:04 volumio sudo[1213]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:24:04 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Feb 23 22:24:04 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Feb 23 22:24:04 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Feb 23 22:24:04 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Feb 23 22:24:04 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Feb 23 22:24:04 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Feb 23 22:24:04 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Feb 23 22:24:04 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Feb 23 22:24:04 volumio sudo[1213]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:04 volumio wireless.js[724]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: JP Feb 23 22:24:04 volumio sudo[1237]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set JP Feb 23 22:24:04 volumio sudo[1237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:24:04 volumio sudo[1237]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:04 volumio wireless.js[724]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: JP Feb 23 22:24:04 volumio wireless.js[724]: WIRELESS.JS - INFO: netconfigured file not found, starting hotspot Feb 23 22:24:04 volumio wireless.js[724]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Feb 23 22:24:04 volumio wireless.js[724]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Feb 23 22:24:04 volumio wireless.js[724]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Feb 23 22:24:04 volumio sudo[1246]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 23 22:24:04 volumio sudo[1246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 22:24:04 volumio sudo[1246]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:04 volumio sudo[1249]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 23 22:24:04 volumio sudo[1249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 22:24:04 volumio sudo[1249]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:04 volumio wpa_supplicant[1252]: Successfully initialized wpa_supplicant Feb 23 22:24:05 volumio wireless.js[724]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 1552604797ms Feb 23 22:24:05 volumio wireless.js[724]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Feb 23 22:24:05 volumio wireless.js[724]: Failed to connect to non-global ctrl_ifname: wlan0 error: No such file or directory Feb 23 22:24:05 volumio wireless.js[724]: WIRELESS.JS - INFO: Notified systemd about wireless ready Feb 23 22:24:05 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 23 22:24:05 volumio systemd[1]: Started wireless.service - Wireless Services. Feb 23 22:24:05 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 23 22:24:05 volumio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Feb 23 22:24:05 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Feb 23 22:24:06 volumio systemd[1]: Received SIGRTMIN+21 from PID 309 (plymouthd). Feb 23 22:24:06 volumio volumio-cpu-tweak[1275]: Setting RT Priority for mpd Feb 23 22:24:06 volumio volumio-cpu-tweak[1289]: pid 35's current scheduling policy: SCHED_OTHER Feb 23 22:24:06 volumio volumio-cpu-tweak[1289]: pid 35's current scheduling priority: 0 Feb 23 22:24:06 volumio volumio-cpu-tweak[1275]: Setting MPD Affinity Feb 23 22:24:06 volumio volumio-cpu-tweak[1290]: pid 3's current affinity mask: f Feb 23 22:24:06 volumio volumio-cpu-tweak[1275]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 23 22:24:06 volumio volumio-cpu-tweak[1275]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Feb 23 22:24:06 volumio systemd[1]: Received SIGRTMIN+21 from PID 309 (plymouthd). Feb 23 22:24:06 volumio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Feb 23 22:24:06 volumio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Feb 23 22:24:06 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Feb 23 22:24:06 volumio systemd[1]: Reached target getty.target - Login Prompts. Feb 23 22:24:06 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Feb 23 22:24:06 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Feb 23 22:24:06 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Feb 23 22:24:06 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Feb 23 22:24:06 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Feb 23 22:24:06 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Feb 23 22:24:06 volumio systemd[1]: Startup finished in 17.916s (kernel) + 20.753s (userspace) = 38.670s. Feb 23 22:24:06 volumio volumio-remote-updater[722]: [2026-02-23 22:24:06] [info] asio async_connect error: asio.system:111 (Connection refused) Feb 23 22:24:06 volumio volumio-remote-updater[722]: [2026-02-23 22:24:06] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Feb 23 22:24:06 volumio volumio-remote-updater[722]: [2026-02-23 22:24:06] [error] handle_connect error: Connection refused Feb 23 22:24:09 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Feb 23 22:24:09 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 23 22:24:09 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 23 22:24:09 volumio upmpdcli[1297]: Could not open config: /tmp/upmpdcli.conf Feb 23 22:24:09 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 23 22:24:09 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 23 22:24:11 volumio volumio[1273]: info: ------------------------------------------- Feb 23 22:24:11 volumio volumio[1273]: info: ----- Volumio3 ---- Feb 23 22:24:11 volumio volumio[1273]: info: ------------------------------------------- Feb 23 22:24:11 volumio volumio[1273]: info: ----- System startup ---- Feb 23 22:24:11 volumio volumio[1273]: info: ------------------------------------------- Feb 23 22:24:12 volumio volumio[1273]: info: MYVOLUMIO Environment detected Feb 23 22:24:13 volumio volumio[1273]: info: Plugin folders cleanup Feb 23 22:24:13 volumio volumio[1273]: info: Scanning into folder /volumio/app/plugins/ Feb 23 22:24:13 volumio volumio[1273]: info: Scanning category audio_interface Feb 23 22:24:13 volumio volumio[1273]: info: Scanning category miscellanea Feb 23 22:24:13 volumio volumio[1273]: info: Scanning category music_service Feb 23 22:24:13 volumio volumio[1273]: info: Scanning category plugins.json Feb 23 22:24:13 volumio volumio[1273]: info: Scanning category system_controller Feb 23 22:24:13 volumio volumio[1273]: info: Scanning category user_interface Feb 23 22:24:13 volumio volumio[1273]: info: Scanning into folder /data/plugins/ Feb 23 22:24:13 volumio volumio[1273]: info: Plugin folders cleanup completed Feb 23 22:24:13 volumio volumio[1273]: info: ------------------------------------------- Feb 23 22:24:13 volumio volumio[1273]: info: ----- Core plugins startup ---- Feb 23 22:24:13 volumio volumio[1273]: info: ------------------------------------------- Feb 23 22:24:13 volumio volumio[1273]: info: Loading plugins from folder /volumio/app/plugins/ Feb 23 22:24:13 volumio volumio[1273]: info: Adding plugin upnp to MyMusic Plugins Feb 23 22:24:13 volumio volumio[1273]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 23 22:24:13 volumio volumio[1273]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 23 22:24:13 volumio volumio[1273]: info: Loading plugins from folder /data/plugins/ Feb 23 22:24:13 volumio volumio[1273]: info: Loading plugin "system"... Feb 23 22:24:13 volumio volumio[1273]: info: Loading plugin "appearance"... Feb 23 22:24:15 volumio volumio[1273]: info: Loading plugin "network"... Feb 23 22:24:15 volumio volumio[1273]: info: Refreshing Cached IP Addresses Feb 23 22:24:15 volumio sudo[1313]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 22:24:15 volumio sudo[1313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:24:15 volumio sudo[1313]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:15 volumio volumio[1273]: info: Loading plugin "services"... Feb 23 22:24:15 volumio volumio[1273]: info: Loading plugin "volumio5onboarding"... Feb 23 22:24:16 volumio sudo[1326]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 23 22:24:16 volumio sudo[1326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:24:16 volumio volumio[1273]: info: Loading plugin "alsa_controller"... Feb 23 22:24:16 volumio sudo[1315]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 22:24:16 volumio sudo[1315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:24:16 volumio sudo[1315]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:16 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 22:24:16 volumio volumio[1273]: info: Loading plugin "wizard"... Feb 23 22:24:16 volumio volumio[1273]: info: Loading plugin "networkfs"... Feb 23 22:24:16 volumio volumio[1273]: info: Starting Udev Watcher for removable devices Feb 23 22:24:16 volumio volumio[1273]: info: Ignoring mount for partition: boot Feb 23 22:24:16 volumio volumio[1273]: info: Ignoring mount for partition: volumio Feb 23 22:24:16 volumio volumio[1273]: info: Ignoring mount for partition: volumio_data Feb 23 22:24:16 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 22:24:16 volumio volumio[1273]: info: Loading plugin "volumio_command_line_client"... Feb 23 22:24:16 volumio volumio[1273]: info: Loading plugin "upnp"... Feb 23 22:24:16 volumio sudo[1343]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=aoi,password=aoi,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.4.50/networkMusic /mnt/NAS/Atom Feb 23 22:24:16 volumio sudo[1343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:24:16 volumio volumio[1273]: info: [1771853056370] Starting Upmpd Daemon Feb 23 22:24:16 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 22:24:16 volumio volumio[1273]: info: Loading plugin "my_music"... Feb 23 22:24:16 volumio volumio[1273]: info: Loading plugin "mpd"... Feb 23 22:24:16 volumio volumio-remote-updater[722]: [2026-02-23 22:24:16] [connect] Successful connection Feb 23 22:24:16 volumio kernel: netfs: FS-Cache loaded Feb 23 22:24:16 volumio sudo[1326]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:16 volumio kernel: Key type cifs.spnego registered Feb 23 22:24:16 volumio kernel: Key type cifs.idmap registered Feb 23 22:24:16 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Feb 23 22:24:16 volumio kernel: CIFS: Attempting to mount //192.168.4.50/networkMusic Feb 23 22:24:17 volumio sudo[1343]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:17 volumio volumio[1273]: info: Loading plugin "upnp_browser"... Feb 23 22:24:19 volumio volumio[1273]: info: Starting UPNP Browser Feb 23 22:24:19 volumio volumio[1273]: info: Loading plugin "alarm-clock"... Feb 23 22:24:20 volumio volumio[1273]: info: Loading plugin "airplay_emulation"... Feb 23 22:24:20 volumio volumio[1273]: info: Starting Shairport Sync Feb 23 22:24:20 volumio volumio[1273]: info: Loading plugin "last_100"... Feb 23 22:24:20 volumio volumio[1273]: info: Loading plugin "webradio"... Feb 23 22:24:20 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Feb 23 22:24:20 volumio volumio[1273]: info: Loading plugin "i2s_dacs"... Feb 23 22:24:20 volumio volumio[1273]: info: Loading plugin "volumiodiscovery"... Feb 23 22:24:20 volumio volumio[1273]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 22:24:20 volumio node[1273]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 22:24:20 volumio volumio[1273]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 22:24:20 volumio node[1273]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 22:24:20 volumio volumio[1273]: *** WARNING *** For more information see Feb 23 22:24:20 volumio node[1273]: *** WARNING *** For more information see Feb 23 22:24:20 volumio volumio[1273]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 22:24:20 volumio node[1273]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 22:24:20 volumio volumio[1273]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 22:24:20 volumio volumio[1273]: *** WARNING *** For more information see Feb 23 22:24:20 volumio node[1273]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 22:24:20 volumio node[1273]: *** WARNING *** For more information see Feb 23 22:24:20 volumio volumio[1273]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 23 22:24:20 volumio volumio[1273]: info: Discovery: Started advertising with name: Volumio Feb 23 22:24:20 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 22:24:20 volumio volumio[1273]: info: Loading plugin "outputs"... Feb 23 22:24:20 volumio volumio[1273]: info: Loading plugin "albumart"... Feb 23 22:24:20 volumio volumio[1273]: info: Plugin example_plugin is not enabled Feb 23 22:24:20 volumio volumio[1273]: info: Loading plugin "inputs"... Feb 23 22:24:20 volumio volumio[1273]: info: Loading plugin "updater_comm"... Feb 23 22:24:20 volumio volumio[1273]: info: Plugin mpdemulation is not enabled Feb 23 22:24:20 volumio volumio[1273]: info: Loading plugin "rest_api"... Feb 23 22:24:20 volumio volumio[1273]: info: Loading plugin "websocket"... Feb 23 22:24:20 volumio volumio[1273]: info: Starting Socket.io Server version 1.7.4 Feb 23 22:24:20 volumio volumio[1273]: info: Loading i18n strings for locale ja Feb 23 22:24:21 volumio volumio[1273]: Updating browse sources language Feb 23 22:24:21 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 22:24:21 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 22:24:21 volumio volumio[1273]: info: CoreCommandRouter::initPlayerControls Feb 23 22:24:21 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:21 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:21 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:21 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:21 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:21 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:21 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:21 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:21 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 22:24:21 volumio volumio[1273]: Express server listening on port 3000 Feb 23 22:24:21 volumio volumio[1273]: [Metrics] WebUI: 12s 185.47ms Feb 23 22:24:21 volumio volumio[1273]: info: CoreStateMachine::resetVolumioState Feb 23 22:24:21 volumio volumio[1273]: info: CoreStateMachine::getcurrentVolume Feb 23 22:24:21 volumio volumio[1273]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 22:24:21 volumio volumio[1273]: info: CoreStateMachine::pushState Feb 23 22:24:21 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Feb 23 22:24:21 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 22:24:21 volumio volumio[1273]: info: CoreCommandRouter::volumioPushState Feb 23 22:24:22 volumio volumio[1273]: info: Volumio Network Manager: Network status updated: 1 Feb 23 22:24:22 volumio volumio[1381]: Forking 3 albumart workers Feb 23 22:24:22 volumio volumio[1273]: verbose: New Socket.io Connection to 192.168.4.41 from 192.168.4.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Feb 23 22:24:23 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 22:24:23 volumio volumio[1273]: info: Reloading queue from file Feb 23 22:24:23 volumio volumio[1273]: verbose: New Socket.io Connection to 192.168.4.41 from 192.168.4.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 2 Feb 23 22:24:23 volumio volumio[1273]: info: CoreStateMachine::setRepeat null single undefined Feb 23 22:24:23 volumio volumio[1273]: info: CoreStateMachine::pushState Feb 23 22:24:23 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Feb 23 22:24:23 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 22:24:23 volumio volumio[1273]: info: CoreCommandRouter::volumioPushState Feb 23 22:24:23 volumio volumio[1273]: info: CoreStateMachine::setRandom null Feb 23 22:24:23 volumio volumio[1273]: info: CoreStateMachine::pushState Feb 23 22:24:23 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Feb 23 22:24:23 volumio volumio[1273]: info: CoreCommandRouter::volumioPushState Feb 23 22:24:23 volumio volumio[1273]: info: Setting Device type: Raspberry PI Feb 23 22:24:23 volumio volumio[1273]: info: Completed loading Core Plugins Feb 23 22:24:23 volumio volumio[1273]: info: Preparing to generate the ALSA configuration file Feb 23 22:24:23 volumio volumio[1273]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 23 22:24:23 volumio volumio[1273]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a32082 Feb 23 22:24:23 volumio volumio[1273]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 23 22:24:24 volumio volumio[1273]: info: Asound.conf file unchanged, so no further update is needed Feb 23 22:24:24 volumio volumio[1273]: info: Output device has changed, restarting MPD Feb 23 22:24:24 volumio volumio[1273]: info: Output device has changed, restarting Shairport Sync Feb 23 22:24:24 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:24 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 22:24:24 volumio sudo[1434]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 22:24:24 volumio sudo[1434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:24:24 volumio sudo[1434]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:24 volumio sudo[1436]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 22:24:24 volumio sudo[1436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:24:24 volumio volumio[1273]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 22:24:24 volumio volumio[1273]: info: ___________ START PLUGINS ___________ Feb 23 22:24:24 volumio volumio[1273]: info: ControllerMpd::onStart: Initializing MPD Feb 23 22:24:24 volumio volumio[1273]: info: Creating MPD Configuration file Feb 23 22:24:24 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 23 22:24:24 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 23 22:24:24 volumio sudo[1445]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 23 22:24:24 volumio sudo[1447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 22:24:24 volumio sudo[1447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:24:24 volumio sudo[1445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:24:24 volumio sudo[1447]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:24 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 22:24:24 volumio volumio[1273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 22:24:24 volumio volumio[1273]: info: [1771853064848] CoreMusicLibrary::Adding element DLNAサーバー Feb 23 22:24:24 volumio sudo[1448]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 22:24:24 volumio sudo[1448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 22:24:24 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Feb 23 22:24:24 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 23 22:24:24 volumio sudo[1448]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:24 volumio sudo[1453]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 23 22:24:24 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 22:24:24 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 23 22:24:24 volumio upmpdcli[1454]: Could not open config: /tmp/upmpdcli.conf Feb 23 22:24:25 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 23 22:24:25 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 23 22:24:25 volumio volumio[1273]: info: UPNP Browser: Client initialized successfully Feb 23 22:24:25 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 23 22:24:25 volumio sudo[1451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 22:24:25 volumio sudo[1445]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:25 volumio sudo[1451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:24:25 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:25 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 23 22:24:25 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 23 22:24:25 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 22:24:25 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 23 22:24:25 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 23 22:24:25 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 23 22:24:25 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 23 22:24:25 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 23 22:24:25 volumio volumio[1273]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 22:24:25 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:25 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 22:24:25 volumio sudo[1463]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 22:24:25 volumio sudo[1463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 22:24:25 volumio sudo[1473]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 23 22:24:25 volumio sudo[1463]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:25 volumio volumio[1273]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 22:24:25 volumio volumio[1273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 22:24:25 volumio volumio[1273]: info: [1771853065857] CoreMusicLibrary::Adding element Last_100 Feb 23 22:24:25 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 22:24:25 volumio volumio[1273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 22:24:25 volumio volumio[1273]: info: [1771853065919] CoreMusicLibrary::Adding element Webradio Feb 23 22:24:25 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 22:24:26 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 22:24:26 volumio volumio[1273]: info: Initializing BBC Radios Feb 23 22:24:26 volumio volumio5-onboarding[1456]: time=2026-02-23T22:24:26.340+09:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 23 22:24:26 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 22:24:26 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 22:24:26 volumio volumio[1273]: info: Volumio Calling Home Feb 23 22:24:27 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Feb 23 22:24:31 volumio volumio-remote-updater[722]: [2026-02-23 22:24:31] [connect] Successful connection Feb 23 22:24:32 volumio volumio[1396]: Starting albumart workers Feb 23 22:24:33 volumio volumio[1273]: info: Discovery: adding 20b7cea2-d408-41fa-ae33-a4e87e6e5e8b Feb 23 22:24:33 volumio volumio[1273]: info: Discovery: Found device Volumio Feb 23 22:24:33 volumio volumio[1273]: info: CoreCommandRouter::volumioGetState Feb 23 22:24:33 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Feb 23 22:24:33 volumio sudo[1511]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 22:24:33 volumio sudo[1511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:24:33 volumio sudo[1511]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:33 volumio sudo[1513]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 22:24:33 volumio sudo[1513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:24:33 volumio volumio[1273]: info: Discovery: this is already registered, 20b7cea2-d408-41fa-ae33-a4e87e6e5e8b Feb 23 22:24:33 volumio volumio[1273]: info: Discovery: Found device Volumio Feb 23 22:24:33 volumio volumio[1273]: info: CoreCommandRouter::volumioGetState Feb 23 22:24:33 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Feb 23 22:24:33 volumio volumio[1398]: Starting albumart workers Feb 23 22:24:34 volumio sudo[1513]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:34 volumio volumio[1397]: Starting albumart workers Feb 23 22:24:34 volumio volumio[1273]: info: MPD Permissions set Feb 23 22:24:34 volumio volumio[1273]: info: MPD Permissions set Feb 23 22:24:34 volumio volumio[1273]: 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: 3 Feb 23 22:24:35 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 23 22:24:35 volumio volumio[1273]: info: Volumio called home Feb 23 22:24:35 volumio volumio[1273]: 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: 3 Feb 23 22:24:35 volumio volumio[1273]: info: Received Get System Info Feb 23 22:24:35 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 22:24:35 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 22:24:35 volumio volumio[1273]: info: Discovery: Getting this device information Feb 23 22:24:35 volumio volumio[1273]: info: CoreCommandRouter::volumioGetState Feb 23 22:24:35 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Feb 23 22:24:35 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 22:24:36 volumio volumio5-onboarding[1456]: time=2026-02-23T22:24:36.028+09:00 level=INFO msg="system info for 8de36494424216e65559951b7b121e4e" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.096 Feb 23 22:24:36 volumio mpd[1474]: 2026-02-23T22:24:36 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 23 22:24:36 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 23 22:24:36 volumio sudo[1436]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:36 volumio sudo[1451]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:37 volumio volumio[1273]: verbose: New Socket.io Connection to 192.168.4.41 from 192.168.4.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 4 Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 22:24:37 volumio volumio[1273]: info: Received Get System Info Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 22:24:37 volumio volumio[1273]: info: Discovery: Getting this device information Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::volumioGetState Feb 23 22:24:37 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Feb 23 22:24:37 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 22:24:38 volumio volumio[1273]: info: Completed starting Core Plugins Feb 23 22:24:38 volumio volumio[1273]: info: ------------------------------------------- Feb 23 22:24:38 volumio volumio[1273]: info: ----- MyVolumio plugins startup ---- Feb 23 22:24:38 volumio volumio[1273]: info: ------------------------------------------- Feb 23 22:24:38 volumio volumio[1273]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 23 22:24:38 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 23 22:24:38 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 23 22:24:38 volumio volumio5-onboarding[1456]: time=2026-02-23T22:24:38.610+09:00 level=INFO msg="enabling local network discovery" Feb 23 22:24:38 volumio volumio5-onboarding[1456]: time=2026-02-23T22:24:38.657+09:00 level=INFO msg="bootstrapping state" hasInternet=true Feb 23 22:24:38 volumio volumio[1273]: verbose: New Socket.io Connection to 192.168.4.41 from 192.168.4.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 5 Feb 23 22:24:39 volumio volumio[1273]: info: Received Get System Info Feb 23 22:24:39 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 22:24:39 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 22:24:39 volumio volumio[1273]: info: Discovery: Getting this device information Feb 23 22:24:39 volumio volumio[1273]: info: CoreCommandRouter::volumioGetState Feb 23 22:24:39 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Feb 23 22:24:39 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 22:24:39 volumio volumio[1273]: error: MPD error: The expression evaluated to a falsy value: Feb 23 22:24:39 volumio volumio[1273]: assert.ok(self.idling) Feb 23 22:24:39 volumio volumio[1273]: error: The expression evaluated to a falsy value: Feb 23 22:24:39 volumio volumio[1273]: assert.ok(self.idling) Feb 23 22:24:39 volumio volumio[1273]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 23 22:24:39 volumio volumio[1273]: info: MPD running with PID1474 Feb 23 22:24:39 volumio volumio[1273]: ,establishing connection Feb 23 22:24:40 volumio volumio[1273]: info: Starting Shairport Sync Feb 23 22:24:40 volumio volumio[1273]: info: Starting Shairport Sync Feb 23 22:24:40 volumio volumio[1273]: info: Starting Shairport Sync Feb 23 22:24:40 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Feb 23 22:24:40 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 23 22:24:40 volumio sudo[1591]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 22:24:40 volumio sudo[1591]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:24:40 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 23 22:24:40 volumio sudo[1589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 22:24:40 volumio sudo[1589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:24:40 volumio sudo[1593]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 22:24:40 volumio sudo[1593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:24:40 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 23 22:24:40 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 23 22:24:40 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 22:24:40 volumio systemd[1]: shairport-sync.service: Consumed 2.008s CPU time. Feb 23 22:24:40 volumio volumio[1273]: error: updateQueue error: null Feb 23 22:24:40 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 22:24:40 volumio sudo[1589]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:40 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 23 22:24:40 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 23 22:24:40 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 22:24:40 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 22:24:40 volumio sudo[1591]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:40 volumio sudo[1593]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:40 volumio volumio[1273]: verbose: New Socket.io Connection to 192.168.4.41 from 192.168.4.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 7 Feb 23 22:24:41 volumio volumio[1273]: info: Shairport-Sync Started Feb 23 22:24:41 volumio volumio[1273]: Error adding Membership: Error: addMembership EINVAL Feb 23 22:24:41 volumio volumio[1273]: info: Shairport-Sync Started Feb 23 22:24:41 volumio volumio[1273]: info: Shairport-Sync Started Feb 23 22:24:41 volumio volumio[1273]: error: updateQueue error: null Feb 23 22:24:41 volumio sudo[1625]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 23 22:24:41 volumio sudo[1625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:24:41 volumio sudo[1625]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:41 volumio volumio[1273]: info: Upmpdcli Daemon Started Feb 23 22:24:42 volumio volumio[1273]: info: CoreCommandRouter::volumioGetState Feb 23 22:24:42 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Feb 23 22:24:42 volumio sudo[1639]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 22:24:42 volumio sudo[1639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:24:42 volumio sudo[1639]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:43 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 23 22:24:43 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 23 22:24:43 volumio systemd[1]: setdatetime-helper.service: Consumed 1.068s CPU time. Feb 23 22:24:43 volumio sudo[1646]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 22:24:43 volumio sudo[1646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:24:43 volumio sudo[1646]: pam_unix(sudo:session): session closed for user root Feb 23 22:24:43 volumio volumio[1273]: verbose: New Socket.io Connection to 192.168.4.41 from 192.168.4.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 8 Feb 23 22:24:43 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 22:24:44 volumio volumio[1273]: info: CoreCommandRouter::volumioGetVisibleSources Feb 23 22:24:44 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 22:24:44 volumio volumio[1273]: info: CoreCommandRouter::volumioGetState Feb 23 22:24:44 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Feb 23 22:24:44 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 23 22:24:44 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 22:24:44 volumio volumio[1273]: info: Received Get System Info Feb 23 22:24:44 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 22:24:44 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 22:24:44 volumio volumio[1273]: info: Discovery: Getting this device information Feb 23 22:24:44 volumio volumio[1273]: info: CoreCommandRouter::volumioGetState Feb 23 22:24:44 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Feb 23 22:24:44 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 22:24:44 volumio volumio[1273]: info: CoreCommandRouter::volumioGetState Feb 23 22:24:44 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Feb 23 22:24:44 volumio volumio[1273]: info: Listing playlists Feb 23 22:24:44 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 23 22:24:44 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 23 22:24:44 volumio volumio[1273]: info: CoreCommandRouter::volumioGetState Feb 23 22:24:44 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Feb 23 22:24:44 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 22:24:44 volumio volumio[1273]: info: Received Get System Info Feb 23 22:24:44 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 22:24:44 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 22:24:44 volumio volumio[1273]: info: Discovery: Getting this device information Feb 23 22:24:44 volumio volumio[1273]: info: CoreCommandRouter::volumioGetState Feb 23 22:24:44 volumio volumio[1273]: info: CorePlayQueue::getTrack 0 Feb 23 22:24:44 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 22:24:45 volumio volumio[1273]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 23 22:24:46 volumio volumio-remote-updater[722]: [2026-02-23 22:24:46] [connect] Successful connection Feb 23 22:24:47 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 23 22:24:55 volumio volumio[1273]: Cannot compose Albumart path Feb 23 22:24:57 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 23 22:24:57 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 22:24:57 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 22:24:57 volumio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 22:24:57 volumio volumio[1273]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 22:24:57 volumio volumio[1273]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Feb 23 22:24:57 volumio volumio[1273]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Feb 23 22:24:57 volumio volumio[1273]: at new NodeError (node:internal/errors:405:5) Feb 23 22:24:57 volumio volumio[1273]: at assert (node:internal/assert:14:11) Feb 23 22:24:57 volumio volumio[1273]: at internalConnectMultiple (node:net:1118:3) Feb 23 22:24:57 volumio volumio[1273]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Feb 23 22:24:57 volumio volumio[1273]: at listOnTimeout (node:internal/timers:575:11) Feb 23 22:24:57 volumio volumio[1273]: at process.processTimers (node:internal/timers:514:7) { Feb 23 22:24:57 volumio volumio[1273]: code: 'ERR_INTERNAL_ASSERTION' Feb 23 22:24:57 volumio volumio[1273]: } Feb 23 22:24:57 volumio volumio[1273]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 22:25:01 volumio volumio-remote-updater[722]: [2026-02-23 22:25:01] [connect] Successful connection Feb 23 22:25:06 volumio sudo[1694]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-23 22:24' Feb 23 22:25:06 volumio sudo[1694]: 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"