Feb 22 10:04:03 volumio volumio-remote-updater[686]: [2026-02-22 10:04:03] [connect] Successful connection Feb 22 10:04:05 volumio volumio[1225]: info: ------------------------------------------- Feb 22 10:04:05 volumio volumio[1225]: info: ----- Volumio3 ---- Feb 22 10:04:05 volumio volumio[1225]: info: ------------------------------------------- Feb 22 10:04:05 volumio volumio[1225]: info: ----- System startup ---- Feb 22 10:04:05 volumio volumio[1225]: info: ------------------------------------------- Feb 22 10:04:05 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Feb 22 10:04:07 volumio volumio[1225]: info: MYVOLUMIO Environment detected Feb 22 10:04:07 volumio volumio[1225]: info: Plugin folders cleanup Feb 22 10:04:07 volumio volumio[1225]: info: Scanning into folder /volumio/app/plugins/ Feb 22 10:04:07 volumio volumio[1225]: info: Scanning category audio_interface Feb 22 10:04:07 volumio volumio[1225]: info: Scanning category miscellanea Feb 22 10:04:07 volumio volumio[1225]: info: Scanning category music_service Feb 22 10:04:07 volumio volumio[1225]: info: Scanning category plugins.json Feb 22 10:04:07 volumio volumio[1225]: info: Scanning category system_controller Feb 22 10:04:07 volumio volumio[1225]: info: Scanning category user_interface Feb 22 10:04:07 volumio volumio[1225]: info: Scanning into folder /data/plugins/ Feb 22 10:04:07 volumio volumio[1225]: info: Plugin folders cleanup completed Feb 22 10:04:07 volumio volumio[1225]: info: ------------------------------------------- Feb 22 10:04:07 volumio volumio[1225]: info: ----- Core plugins startup ---- Feb 22 10:04:07 volumio volumio[1225]: info: ------------------------------------------- Feb 22 10:04:07 volumio volumio[1225]: info: Loading plugins from folder /volumio/app/plugins/ Feb 22 10:04:07 volumio volumio[1225]: info: Adding plugin upnp to MyMusic Plugins Feb 22 10:04:07 volumio volumio[1225]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 22 10:04:07 volumio volumio[1225]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 22 10:04:07 volumio volumio[1225]: info: Loading plugins from folder /data/plugins/ Feb 22 10:04:07 volumio volumio[1225]: info: Loading plugin "system"... Feb 22 10:04:08 volumio volumio[1225]: info: Loading plugin "appearance"... Feb 22 10:04:11 volumio volumio[1225]: info: Loading plugin "network"... Feb 22 10:04:11 volumio volumio[1225]: info: Refreshing Cached IP Addresses Feb 22 10:04:11 volumio sudo[1264]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 10:04:11 volumio volumio[1225]: info: Loading plugin "services"... Feb 22 10:04:11 volumio sudo[1264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:04:11 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Feb 22 10:04:11 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 22 10:04:11 volumio volumio[1225]: info: Loading plugin "alsa_controller"... Feb 22 10:04:11 volumio sudo[1262]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 10:04:11 volumio sudo[1264]: pam_unix(sudo:session): session closed for user root Feb 22 10:04:11 volumio sudo[1262]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:04:11 volumio sudo[1262]: pam_unix(sudo:session): session closed for user root Feb 22 10:04:11 volumio sudo[1271]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 22 10:04:11 volumio sudo[1271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:04:11 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 22 10:04:12 volumio upmpdcli[1279]: Could not open config: /tmp/upmpdcli.conf Feb 22 10:04:12 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 22 10:04:12 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 22 10:04:12 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 10:04:12 volumio volumio[1225]: info: Loading plugin "wizard"... Feb 22 10:04:12 volumio volumio[1225]: info: Loading plugin "networkfs"... Feb 22 10:04:12 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 22 10:04:12 volumio volumio[1225]: info: Starting Udev Watcher for removable devices Feb 22 10:04:12 volumio sudo[1295]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=gusl6,password=mj0216,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.38/music /mnt/NAS/MyNas Feb 22 10:04:12 volumio sudo[1295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:04:12 volumio sudo[1271]: pam_unix(sudo:session): session closed for user root Feb 22 10:04:12 volumio volumio[1225]: info: Ignoring mount for partition: boot Feb 22 10:04:12 volumio volumio[1225]: info: Ignoring mount for partition: volumio Feb 22 10:04:12 volumio volumio[1225]: info: Ignoring mount for partition: volumio_data Feb 22 10:04:12 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 22 10:04:12 volumio volumio[1225]: info: Loading plugin "volumio_command_line_client"... Feb 22 10:04:12 volumio volumio[1225]: info: Loading plugin "upnp"... Feb 22 10:04:12 volumio kernel: netfs: FS-Cache loaded Feb 22 10:04:12 volumio volumio[1225]: info: [1771722252861] Starting Upmpd Daemon Feb 22 10:04:12 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 22 10:04:12 volumio volumio[1225]: info: Loading plugin "my_music"... Feb 22 10:04:12 volumio volumio[1225]: info: Loading plugin "mpd"... Feb 22 10:04:13 volumio kernel: Key type cifs.spnego registered Feb 22 10:04:13 volumio kernel: Key type cifs.idmap registered Feb 22 10:04:13 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 22 10:04:13 volumio kernel: CIFS: Attempting to mount //192.168.0.38/music Feb 22 10:04:13 volumio sudo[1295]: pam_unix(sudo:session): session closed for user root Feb 22 10:04:14 volumio volumio[1225]: info: Loading plugin "upnp_browser"... Feb 22 10:04:17 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Feb 22 10:04:18 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 22 10:04:18 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 22 10:04:18 volumio systemd[1]: setdatetime-helper.service: Consumed 2.169s CPU time. Feb 22 10:04:18 volumio volumio-remote-updater[686]: [2026-02-22 10:04:18] [connect] Successful connection Feb 22 10:04:19 volumio volumio[1225]: info: Starting UPNP Browser Feb 22 10:04:19 volumio volumio[1225]: info: Loading plugin "alarm-clock"... Feb 22 10:04:20 volumio volumio[1225]: info: Loading plugin "airplay_emulation"... Feb 22 10:04:20 volumio volumio[1225]: info: Starting Shairport Sync Feb 22 10:04:20 volumio volumio[1225]: info: Loading plugin "last_100"... Feb 22 10:04:20 volumio volumio[1225]: info: Loading plugin "webradio"... Feb 22 10:04:20 volumio volumio[1225]: info: Loading plugin "i2s_dacs"... Feb 22 10:04:20 volumio volumio[1225]: info: Loading plugin "volumiodiscovery"... Feb 22 10:04:20 volumio volumio[1225]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 22 10:04:20 volumio node[1225]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 22 10:04:20 volumio volumio[1225]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 22 10:04:20 volumio node[1225]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 22 10:04:20 volumio volumio[1225]: *** WARNING *** For more information see Feb 22 10:04:20 volumio node[1225]: *** WARNING *** For more information see Feb 22 10:04:20 volumio volumio[1225]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 22 10:04:20 volumio node[1225]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 22 10:04:20 volumio volumio[1225]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 22 10:04:20 volumio node[1225]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 22 10:04:20 volumio volumio[1225]: *** WARNING *** For more information see Feb 22 10:04:20 volumio node[1225]: *** WARNING *** For more information see Feb 22 10:04:20 volumio volumio[1225]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 22 10:04:20 volumio volumio[1225]: info: Discovery: Started advertising with name: Volumio Feb 22 10:04:20 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 22 10:04:20 volumio volumio[1225]: info: Loading plugin "outputs"... Feb 22 10:04:21 volumio volumio[1225]: info: Loading plugin "albumart"... Feb 22 10:04:21 volumio volumio[1225]: info: Plugin example_plugin is not enabled Feb 22 10:04:21 volumio volumio[1225]: info: Loading plugin "inputs"... Feb 22 10:04:21 volumio volumio[1225]: info: Loading plugin "updater_comm"... Feb 22 10:04:21 volumio volumio[1225]: info: Plugin mpdemulation is not enabled Feb 22 10:04:21 volumio volumio[1225]: info: Loading plugin "rest_api"... Feb 22 10:04:21 volumio volumio[1225]: info: Loading plugin "websocket"... Feb 22 10:04:21 volumio volumio[1225]: info: Starting Socket.io Server version 1.7.4 Feb 22 10:04:22 volumio volumio[1225]: info: Loading i18n strings for locale ko Feb 22 10:04:22 volumio volumio[1225]: Updating browse sources language Feb 22 10:04:22 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 10:04:23 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 10:04:23 volumio volumio[1225]: info: CoreCommandRouter::initPlayerControls Feb 22 10:04:23 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:23 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:23 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:23 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:23 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:23 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:23 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:23 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:23 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 10:04:23 volumio volumio[1225]: Express server listening on port 3000 Feb 22 10:04:23 volumio volumio[1225]: [Metrics] WebUI: 20s 918.43ms Feb 22 10:04:23 volumio volumio[1225]: info: CoreStateMachine::resetVolumioState Feb 22 10:04:23 volumio volumio[1225]: info: CoreStateMachine::getcurrentVolume Feb 22 10:04:23 volumio volumio[1225]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 10:04:23 volumio volumio[1225]: info: CoreStateMachine::pushState Feb 22 10:04:23 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Feb 22 10:04:23 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 10:04:23 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Feb 22 10:04:24 volumio sudo[1389]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 10:04:24 volumio sudo[1389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:04:24 volumio sudo[1389]: pam_unix(sudo:session): session closed for user root Feb 22 10:04:24 volumio sudo[1391]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 10:04:24 volumio sudo[1391]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:04:24 volumio sudo[1391]: pam_unix(sudo:session): session closed for user root Feb 22 10:04:24 volumio volumio[1225]: info: Volumio Network Manager: Network status updated: 1 Feb 22 10:04:24 volumio volumio[1361]: Forking 3 albumart workers Feb 22 10:04:25 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 10:04:25 volumio volumio[1225]: info: Reloading queue from file Feb 22 10:04:25 volumio volumio[1225]: info: CoreStateMachine::setRepeat null single undefined Feb 22 10:04:25 volumio volumio[1225]: info: CoreStateMachine::pushState Feb 22 10:04:25 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Feb 22 10:04:25 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 10:04:25 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Feb 22 10:04:25 volumio volumio[1225]: info: CoreStateMachine::setRandom null Feb 22 10:04:25 volumio volumio[1225]: info: CoreStateMachine::pushState Feb 22 10:04:25 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Feb 22 10:04:25 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Feb 22 10:04:25 volumio volumio[1225]: info: Setting Device type: Raspberry PI Feb 22 10:04:26 volumio volumio[1225]: info: Completed loading Core Plugins Feb 22 10:04:26 volumio volumio[1225]: info: Preparing to generate the ALSA configuration file Feb 22 10:04:26 volumio volumio[1225]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 22 10:04:26 volumio volumio[1225]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a32082 Feb 22 10:04:26 volumio volumio[1225]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 22 10:04:27 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Feb 22 10:04:27 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 22 10:04:27 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 22 10:04:27 volumio volumio[1225]: info: Asound.conf file unchanged, so no further update is needed Feb 22 10:04:27 volumio volumio[1225]: info: Output device has changed, restarting MPD Feb 22 10:04:27 volumio sudo[1434]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 22 10:04:27 volumio sudo[1434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:04:27 volumio volumio[1225]: info: Output device has changed, restarting Shairport Sync Feb 22 10:04:27 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:27 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 10:04:27 volumio sudo[1447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 10:04:27 volumio sudo[1447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:04:27 volumio sudo[1447]: pam_unix(sudo:session): session closed for user root Feb 22 10:04:28 volumio sudo[1449]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 10:04:28 volumio sudo[1449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:04:28 volumio sudo[1434]: pam_unix(sudo:session): session closed for user root Feb 22 10:04:28 volumio volumio[1225]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 10:04:28 volumio volumio[1225]: info: ___________ START PLUGINS ___________ Feb 22 10:04:28 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 10:04:28 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 10:04:28 volumio volumio[1225]: info: ControllerMpd::onStart: Initializing MPD Feb 22 10:04:28 volumio volumio[1225]: info: Creating MPD Configuration file Feb 22 10:04:28 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 10:04:28 volumio volumio[1225]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 10:04:28 volumio volumio[1225]: info: [1771722268773] CoreMusicLibrary::Adding element 미디어 서버 Feb 22 10:04:28 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 10:04:28 volumio sudo[1458]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 10:04:28 volumio sudo[1458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 10:04:28 volumio sudo[1463]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 22 10:04:28 volumio sudo[1458]: pam_unix(sudo:session): session closed for user root Feb 22 10:04:29 volumio sudo[1460]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 10:04:29 volumio volumio[1225]: info: UPNP Browser: Client initialized successfully Feb 22 10:04:29 volumio sudo[1462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 10:04:29 volumio sudo[1460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:04:29 volumio sudo[1460]: pam_unix(sudo:session): session closed for user root Feb 22 10:04:29 volumio sudo[1462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:04:29 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:29 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 10:04:29 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 22 10:04:29 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 22 10:04:29 volumio systemd[1]: mpd.service: Consumed 1.070s CPU time. Feb 22 10:04:29 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 22 10:04:29 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 22 10:04:29 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 22 10:04:29 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 10:04:29 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 10:04:30 volumio volumio[1225]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 10:04:30 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:30 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 10:04:30 volumio sudo[1472]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 10:04:30 volumio sudo[1472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 10:04:30 volumio sudo[1477]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 22 10:04:30 volumio sudo[1472]: pam_unix(sudo:session): session closed for user root Feb 22 10:04:30 volumio volumio[1225]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 10:04:30 volumio volumio[1225]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 10:04:30 volumio volumio[1225]: info: [1771722270594] CoreMusicLibrary::Adding element Last_100 Feb 22 10:04:30 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 10:04:30 volumio volumio[1225]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 10:04:30 volumio volumio[1225]: info: [1771722270726] CoreMusicLibrary::Adding element Webradio Feb 22 10:04:30 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 10:04:30 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 10:04:31 volumio volumio[1225]: info: Initializing BBC Radios Feb 22 10:04:31 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 10:04:31 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 10:04:31 volumio volumio[1225]: info: Volumio Calling Home Feb 22 10:04:33 volumio volumio-remote-updater[686]: [2026-02-22 10:04:33] [connect] Successful connection Feb 22 10:04:42 volumio mpd[1478]: 2026-02-22T10:04:42 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 22 10:04:42 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 22 10:04:42 volumio sudo[1462]: pam_unix(sudo:session): session closed for user root Feb 22 10:04:42 volumio sudo[1449]: pam_unix(sudo:session): session closed for user root Feb 22 10:04:44 volumio volumio[1225]: info: Discovery: adding c5561eca-4d4b-42e1-b284-015deee454ec Feb 22 10:04:44 volumio volumio[1225]: info: Discovery: Found device Volumio Feb 22 10:04:44 volumio volumio[1225]: info: CoreCommandRouter::volumioGetState Feb 22 10:04:44 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Feb 22 10:04:44 volumio volumio[1225]: info: Discovery: this is already registered, c5561eca-4d4b-42e1-b284-015deee454ec Feb 22 10:04:44 volumio volumio[1225]: info: Discovery: Found device Volumio Feb 22 10:04:44 volumio volumio[1225]: info: CoreCommandRouter::volumioGetState Feb 22 10:04:44 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Feb 22 10:04:45 volumio volumio[1225]: info: Completed starting Core Plugins Feb 22 10:04:45 volumio volumio[1225]: info: ------------------------------------------- Feb 22 10:04:45 volumio volumio[1225]: info: ----- MyVolumio plugins startup ---- Feb 22 10:04:45 volumio volumio[1225]: info: ------------------------------------------- Feb 22 10:04:45 volumio volumio[1225]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 22 10:04:45 volumio volumio[1225]: info: MPD Permissions set Feb 22 10:04:45 volumio volumio[1225]: info: MPD Permissions set Feb 22 10:04:45 volumio volumio[1225]: info: Upmpdcli Daemon Started Feb 22 10:04:45 volumio volumio[1225]: info: Volumio called home Feb 22 10:04:46 volumio volumio[1396]: Starting albumart workers Feb 22 10:04:46 volumio volumio[1397]: Starting albumart workers Feb 22 10:04:46 volumio volumio[1395]: Starting albumart workers Feb 22 10:04:48 volumio volumio-remote-updater[686]: [2026-02-22 10:04:48] [connect] Successful connection Feb 22 10:04:49 volumio volumio[1225]: error: MPD error: The expression evaluated to a falsy value: Feb 22 10:04:49 volumio volumio[1225]: assert.ok(self.idling) Feb 22 10:04:49 volumio volumio[1225]: error: The expression evaluated to a falsy value: Feb 22 10:04:49 volumio volumio[1225]: assert.ok(self.idling) Feb 22 10:04:49 volumio volumio[1225]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 22 10:04:49 volumio volumio[1225]: info: MPD running with PID1478 Feb 22 10:04:49 volumio volumio[1225]: ,establishing connection Feb 22 10:04:49 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:49 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:49 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:49 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:49 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:49 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:49 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:49 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:50 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 10:04:50 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:50 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:50 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:50 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:50 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:50 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:50 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:50 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:50 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:50 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 10:04:50 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:04:50 volumio volumio[1225]: error: updateQueue error: null Feb 22 10:04:52 volumio volumio-remote-updater[686]: [2026-02-22 10:04:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771722288 101 Feb 22 10:04:52 volumio volumio[1225]: 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: 2 Feb 22 10:04:52 volumio volumio[1225]: error: updateQueue error: null Feb 22 10:04:52 volumio volumio[1225]: info: Starting Shairport Sync Feb 22 10:04:52 volumio volumio[1225]: info: Starting Shairport Sync Feb 22 10:04:52 volumio volumio[1225]: info: Starting Shairport Sync Feb 22 10:04:53 volumio sudo[1544]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 10:04:53 volumio sudo[1544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:04:53 volumio sudo[1546]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 10:04:53 volumio sudo[1546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:04:53 volumio sudo[1548]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 10:04:53 volumio sudo[1548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 10:04:53 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 22 10:04:53 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 22 10:04:53 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 10:04:53 volumio systemd[1]: shairport-sync.service: Consumed 2.535s CPU time. Feb 22 10:04:53 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 10:04:53 volumio sudo[1544]: pam_unix(sudo:session): session closed for user root Feb 22 10:04:53 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 22 10:04:53 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 22 10:04:53 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 10:04:53 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 10:04:53 volumio sudo[1546]: pam_unix(sudo:session): session closed for user root Feb 22 10:04:53 volumio sudo[1548]: pam_unix(sudo:session): session closed for user root Feb 22 10:04:54 volumio volumio[1225]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 22 10:04:55 volumio volumio[1225]: info: Shairport-Sync Started Feb 22 10:04:55 volumio volumio[1225]: Error adding Membership: Error: addMembership EINVAL Feb 22 10:04:55 volumio volumio[1225]: info: Shairport-Sync Started Feb 22 10:04:55 volumio volumio[1225]: info: Shairport-Sync Started Feb 22 10:04:59 volumio ntpd[864]: CLOCK: time stepped by 1.006423 Feb 22 10:04:59 volumio ntpd[864]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Feb 22 10:05:06 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 10:05:06 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 10:05:06 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 10:05:06 volumio volumio[1225]: error: MyVolumio Plugin failed to start in a timely fashion Feb 22 10:05:06 volumio volumio[1225]: [Metrics] CommandRouter: 60s 785.90ms Feb 22 10:05:06 volumio volumio[1225]: info: CoreCommandRouter::volumiosetStartupVolume Feb 22 10:05:06 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 10:05:06 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 10:05:07 volumio volumio[1225]: info: CoreCommandRouter::Close All Modals sent Feb 22 10:05:07 volumio volumio[1225]: info: CoreCommandRouter::Close All Modals sent Feb 22 10:05:07 volumio volumio[1225]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 10:05:07 volumio volumio[1225]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Feb 22 10:05:07 volumio volumio[1225]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Feb 22 10:05:07 volumio volumio[1225]: at new NodeError (node:internal/errors:405:5) Feb 22 10:05:07 volumio volumio[1225]: at assert (node:internal/assert:14:11) Feb 22 10:05:07 volumio volumio[1225]: at internalConnectMultiple (node:net:1118:3) Feb 22 10:05:07 volumio volumio[1225]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Feb 22 10:05:07 volumio volumio[1225]: at listOnTimeout (node:internal/timers:575:11) Feb 22 10:05:07 volumio volumio[1225]: at process.processTimers (node:internal/timers:514:7) { Feb 22 10:05:07 volumio volumio[1225]: code: 'ERR_INTERNAL_ASSERTION' Feb 22 10:05:07 volumio volumio[1225]: } Feb 22 10:05:07 volumio volumio[1225]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 10:05:18 volumio sudo[1647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-22 10:04' Feb 22 10:05:18 volumio sudo[1647]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"