Jan 27 10:39:27 volumio ntpd[941]: CLOCK: time stepped by 1372229.506130 Jan 27 10:39:27 volumio ntpd[941]: CLOCK: time changed from 2026-01-11 to 2026-01-27 Jan 27 10:39:27 volumio ntpd[941]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jan 27 10:39:27 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 27 10:39:27 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 27 10:39:27 volumio systemd[1]: setdatetime-helper.service: Consumed 2.133s CPU time. Jan 27 10:39:27 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Jan 27 10:39:27 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Jan 27 10:39:27 volumio volumio[1033]: info: Loading plugin "network"... Jan 27 10:39:27 volumio volumio[1033]: info: Refreshing Cached IP Addresses Jan 27 10:39:27 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Jan 27 10:39:27 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Jan 27 10:39:27 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Jan 27 10:39:28 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Jan 27 10:39:28 volumio volumio[1033]: info: Loading plugin "services"... Jan 27 10:39:28 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jan 27 10:39:28 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Jan 27 10:39:28 volumio volumio[1033]: info: Loading plugin "alsa_controller"... Jan 27 10:39:28 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Jan 27 10:39:28 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Jan 27 10:39:28 volumio systemd[1]: Startup finished in 16.369s (kernel) + 20.432s (userspace) = 36.801s. Jan 27 10:39:28 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 27 10:39:28 volumio sudo[1127]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 27 10:39:28 volumio sudo[1125]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 27 10:39:28 volumio sudo[1141]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 27 10:39:28 volumio sudo[1141]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:28 volumio sudo[1127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:28 volumio sudo[1125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:28 volumio volumio[1033]: info: Loading plugin "wizard"... Jan 27 10:39:28 volumio sudo[1125]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:28 volumio sudo[1141]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:28 volumio sudo[1127]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:28 volumio volumio[1033]: info: Loading plugin "networkfs"... Jan 27 10:39:28 volumio volumio[1033]: info: Starting Udev Watcher for removable devices Jan 27 10:39:28 volumio sudo[1170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Volumio,password=shibA8787,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.34/Music /mnt/NAS/QNAP Jan 27 10:39:28 volumio sudo[1170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:28 volumio volumio[1033]: info: Ignoring mount for partition: boot Jan 27 10:39:28 volumio volumio[1033]: info: Ignoring mount for partition: volumio Jan 27 10:39:28 volumio volumio[1033]: info: Ignoring mount for partition: volumio_data Jan 27 10:39:28 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 27 10:39:28 volumio volumio[1033]: info: Loading plugin "volumio_command_line_client"... Jan 27 10:39:28 volumio volumio[1033]: info: Loading plugin "upnp"... Jan 27 10:39:28 volumio volumio[1033]: info: [1769477968496] Starting Upmpd Daemon Jan 27 10:39:28 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 27 10:39:28 volumio volumio[1033]: info: Loading plugin "my_music"... Jan 27 10:39:28 volumio volumio[1033]: info: Loading plugin "mpd"... Jan 27 10:39:28 volumio kernel: netfs: FS-Cache loaded Jan 27 10:39:28 volumio kernel: Key type cifs.spnego registered Jan 27 10:39:28 volumio kernel: Key type cifs.idmap registered Jan 27 10:39:28 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. Jan 27 10:39:28 volumio kernel: CIFS: Attempting to mount //192.168.0.34/Music Jan 27 10:39:29 volumio sudo[1170]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:29 volumio volumio[1033]: info: Loading plugin "upnp_browser"... Jan 27 10:39:29 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Jan 27 10:39:29 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 27 10:39:30 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 27 10:39:30 volumio upmpdcli[1202]: Could not open config: /tmp/upmpdcli.conf Jan 27 10:39:30 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 27 10:39:30 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 27 10:39:31 volumio volumio[1033]: info: Starting UPNP Browser Jan 27 10:39:31 volumio volumio[1033]: info: Loading plugin "alarm-clock"... Jan 27 10:39:31 volumio volumio[1033]: info: Loading plugin "airplay_emulation"... Jan 27 10:39:31 volumio volumio[1033]: info: Starting Shairport Sync Jan 27 10:39:31 volumio volumio[1033]: info: Loading plugin "last_100"... Jan 27 10:39:31 volumio volumio[1033]: info: Loading plugin "webradio"... Jan 27 10:39:31 volumio volumio[1033]: info: Loading plugin "i2s_dacs"... Jan 27 10:39:31 volumio volumio[1033]: info: I2S DAC not set, start Auto-detection Jan 27 10:39:31 volumio volumio[1033]: info: Loading plugin "volumiodiscovery"... Jan 27 10:39:31 volumio volumio[1033]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 27 10:39:31 volumio volumio[1033]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 27 10:39:31 volumio node[1033]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 27 10:39:31 volumio volumio[1033]: *** WARNING *** For more information see Jan 27 10:39:31 volumio volumio[1033]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 27 10:39:31 volumio volumio[1033]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 27 10:39:31 volumio volumio[1033]: *** WARNING *** For more information see Jan 27 10:39:31 volumio node[1033]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 27 10:39:31 volumio node[1033]: *** WARNING *** For more information see Jan 27 10:39:31 volumio node[1033]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 27 10:39:31 volumio node[1033]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 27 10:39:31 volumio node[1033]: *** WARNING *** For more information see Jan 27 10:39:31 volumio volumio[1033]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 27 10:39:31 volumio volumio[1033]: info: Discovery: Started advertising with name: Volumio Jan 27 10:39:31 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 27 10:39:31 volumio volumio[1033]: info: Loading plugin "outputs"... Jan 27 10:39:31 volumio volumio[1033]: info: Loading plugin "albumart"... Jan 27 10:39:31 volumio volumio[1033]: info: Plugin example_plugin is not enabled Jan 27 10:39:31 volumio volumio[1033]: info: Loading plugin "inputs"... Jan 27 10:39:31 volumio volumio[1033]: info: Loading plugin "updater_comm"... Jan 27 10:39:31 volumio volumio[1033]: info: Plugin mpdemulation is not enabled Jan 27 10:39:31 volumio volumio[1033]: info: Loading plugin "rest_api"... Jan 27 10:39:31 volumio volumio[1033]: info: Loading plugin "websocket"... Jan 27 10:39:32 volumio volumio[1033]: info: Starting Socket.io Server version 1.7.4 Jan 27 10:39:32 volumio volumio[1033]: info: Loading i18n strings for locale ja Jan 27 10:39:32 volumio volumio[1033]: Updating browse sources language Jan 27 10:39:32 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 10:39:32 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 10:39:32 volumio volumio[1033]: info: CoreCommandRouter::initPlayerControls Jan 27 10:39:32 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:32 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:32 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:32 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:32 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:32 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:32 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:32 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:32 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 10:39:32 volumio volumio[1033]: Express server listening on port 3000 Jan 27 10:39:32 volumio volumio[1033]: [Metrics] WebUI: 9s 77.41ms Jan 27 10:39:32 volumio volumio[1033]: info: CoreStateMachine::resetVolumioState Jan 27 10:39:32 volumio volumio[1033]: info: CoreStateMachine::getcurrentVolume Jan 27 10:39:32 volumio volumio[1033]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 10:39:32 volumio volumio[1033]: info: CoreStateMachine::pushState Jan 27 10:39:32 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 27 10:39:32 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 10:39:32 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Jan 27 10:39:32 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 10:39:32 volumio volumio[1033]: info: Volumio Network Manager: Network status updated: 1 Jan 27 10:39:32 volumio volumio[1033]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 27 10:39:32 volumio volumio[1033]: wlan0 Interface doesn't support scanning. Jan 27 10:39:32 volumio volumio[1033]: info: Cannot use regular scanning, forcing with ap-force Jan 27 10:39:32 volumio sudo[1223]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 27 10:39:32 volumio sudo[1223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:32 volumio sudo[1223]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:32 volumio volumio[1033]: command failed: No such device (-19) Jan 27 10:39:32 volumio volumio[1033]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jan 27 10:39:32 volumio volumio[1033]: command failed: No such device (-19) Jan 27 10:39:32 volumio volumio[1207]: Forking 3 albumart workers Jan 27 10:39:32 volumio volumio[1033]: info: Reloading queue from file Jan 27 10:39:33 volumio volumio[1033]: info: CoreStateMachine::setRepeat null single undefined Jan 27 10:39:33 volumio volumio[1033]: info: CoreStateMachine::pushState Jan 27 10:39:33 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 27 10:39:33 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 10:39:33 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Jan 27 10:39:33 volumio volumio[1033]: info: CoreStateMachine::setRandom null Jan 27 10:39:33 volumio volumio[1033]: info: CoreStateMachine::pushState Jan 27 10:39:33 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 27 10:39:33 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Jan 27 10:39:33 volumio volumio[1033]: info: Setting Device type: Raspberry PI Jan 27 10:39:33 volumio volumio[1033]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 27 10:39:33 volumio volumio[1033]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a02100 Jan 27 10:39:33 volumio volumio[1033]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 27 10:39:33 volumio volumio[1033]: info: Completed loading Core Plugins Jan 27 10:39:33 volumio volumio[1033]: info: Preparing to generate the ALSA configuration file Jan 27 10:39:33 volumio volumio[1033]: info: Discovery: adding 029f5e39-3a8c-4b08-9ab2-1c5d96083a94 Jan 27 10:39:33 volumio volumio[1033]: info: Discovery: Found device Volumio Jan 27 10:39:33 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 27 10:39:33 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 27 10:39:33 volumio volumio[1033]: info: Discovery: this is already registered, 029f5e39-3a8c-4b08-9ab2-1c5d96083a94 Jan 27 10:39:33 volumio volumio[1033]: info: Discovery: Found device Volumio Jan 27 10:39:33 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 27 10:39:33 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 27 10:39:33 volumio volumio[1033]: info: Asound.conf file unchanged, so no further update is needed Jan 27 10:39:33 volumio volumio[1033]: info: Output device has changed, restarting MPD Jan 27 10:39:33 volumio volumio[1033]: info: Output device has changed, restarting Shairport Sync Jan 27 10:39:33 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:33 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 10:39:33 volumio sudo[1266]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 27 10:39:33 volumio sudo[1266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:33 volumio sudo[1266]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:33 volumio sudo[1268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 27 10:39:33 volumio sudo[1268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:33 volumio volumio[1033]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 10:39:33 volumio volumio[1033]: info: ___________ START PLUGINS ___________ Jan 27 10:39:33 volumio volumio[1033]: info: ControllerMpd::onStart: Initializing MPD Jan 27 10:39:33 volumio volumio[1033]: info: Creating MPD Configuration file Jan 27 10:39:33 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 10:39:33 volumio volumio[1033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 27 10:39:33 volumio volumio[1033]: info: [1769477973714] CoreMusicLibrary::Adding element DLNAサーバー Jan 27 10:39:33 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 10:39:33 volumio sudo[1276]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 27 10:39:33 volumio sudo[1276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:33 volumio sudo[1276]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:33 volumio sudo[1278]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 27 10:39:33 volumio volumio[1033]: info: UPNP Browser: Client initialized successfully Jan 27 10:39:33 volumio sudo[1278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:33 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 27 10:39:33 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 27 10:39:33 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 27 10:39:33 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 27 10:39:33 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 27 10:39:33 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 27 10:39:33 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 27 10:39:33 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 27 10:39:33 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 27 10:39:34 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:34 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 10:39:34 volumio volumio[1033]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 10:39:34 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:34 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 10:39:34 volumio sudo[1284]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 27 10:39:34 volumio sudo[1284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 27 10:39:34 volumio sudo[1293]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 27 10:39:34 volumio sudo[1284]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:34 volumio volumio[1033]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 10:39:34 volumio volumio[1033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 27 10:39:34 volumio volumio[1033]: info: [1769477974304] CoreMusicLibrary::Adding element Last_100 Jan 27 10:39:34 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 10:39:34 volumio volumio[1033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 27 10:39:34 volumio volumio[1033]: info: [1769477974340] CoreMusicLibrary::Adding element Webradio Jan 27 10:39:34 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 10:39:34 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 27 10:39:34 volumio volumio[1033]: info: Initializing BBC Radios Jan 27 10:39:34 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 27 10:39:34 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 10:39:34 volumio volumio[1033]: info: Volumio Calling Home Jan 27 10:39:38 volumio volumio[1033]: info: MPD Permissions set Jan 27 10:39:38 volumio volumio[1033]: info: MPD Permissions set Jan 27 10:39:39 volumio sudo[1327]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 27 10:39:39 volumio sudo[1327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:39 volumio sudo[1327]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:39 volumio sudo[1330]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 27 10:39:39 volumio sudo[1330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:39 volumio sudo[1330]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:39 volumio volumio[1228]: Starting albumart workers Jan 27 10:39:39 volumio volumio[1033]: info: Volumio called home Jan 27 10:39:39 volumio volumio[1230]: Starting albumart workers Jan 27 10:39:40 volumio volumio[1229]: Starting albumart workers Jan 27 10:39:40 volumio volumio[1033]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jan 27 10:39:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 10:39:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 10:39:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:39:41 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Jan 27 10:39:41 volumio volumio[1033]: verbose: New Socket.io Connection to 192.168.0.226:3000 from 192.168.0.130 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Jan 27 10:39:41 volumio volumio[1033]: info: Starting Shairport Sync Jan 27 10:39:41 volumio volumio[1033]: info: Starting Shairport Sync Jan 27 10:39:41 volumio volumio-remote-updater[645]: [2026-01-27 10:39:41] [connect] Successful connection Jan 27 10:39:41 volumio volumio[1033]: info: Starting Shairport Sync Jan 27 10:39:42 volumio sudo[1348]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 27 10:39:42 volumio sudo[1348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:42 volumio sudo[1350]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 27 10:39:42 volumio sudo[1350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:42 volumio sudo[1352]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 27 10:39:42 volumio sudo[1352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:42 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 10:39:42 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 27 10:39:42 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 27 10:39:42 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 27 10:39:42 volumio systemd[1]: shairport-sync.service: Consumed 1.859s CPU time. Jan 27 10:39:42 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 10:39:42 volumio volumio[1033]: info: Discovery: Getting this device information Jan 27 10:39:42 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 27 10:39:42 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 27 10:39:42 volumio mpd[1294]: 2026-01-27T10:39:42 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 27 10:39:42 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 10:39:42 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 27 10:39:42 volumio sudo[1350]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:42 volumio sudo[1348]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:42 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 27 10:39:42 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 27 10:39:42 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 27 10:39:42 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 27 10:39:42 volumio sudo[1352]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:42 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 27 10:39:42 volumio volumio[1033]: info: Shairport-Sync Started Jan 27 10:39:42 volumio sudo[1268]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:42 volumio sudo[1278]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:42 volumio volumio[1033]: Error adding Membership: Error: addMembership EINVAL Jan 27 10:39:42 volumio volumio[1033]: info: Shairport-Sync Started Jan 27 10:39:42 volumio volumio[1033]: info: Shairport-Sync Started Jan 27 10:39:42 volumio sudo[1375]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 27 10:39:42 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 10:39:42 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 10:39:42 volumio volumio[1033]: info: Discovery: Getting this device information Jan 27 10:39:43 volumio sudo[1375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:43 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 27 10:39:43 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 27 10:39:43 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 10:39:43 volumio volumio[1033]: info: Completed starting Core Plugins Jan 27 10:39:43 volumio volumio[1033]: info: ------------------------------------------- Jan 27 10:39:43 volumio volumio[1033]: info: ----- MyVolumio plugins startup ---- Jan 27 10:39:43 volumio volumio[1033]: info: ------------------------------------------- Jan 27 10:39:43 volumio volumio[1033]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 27 10:39:43 volumio volumio[1033]: error: MPD error: The expression evaluated to a falsy value: Jan 27 10:39:43 volumio volumio[1033]: assert.ok(self.idling) Jan 27 10:39:43 volumio volumio[1033]: error: The expression evaluated to a falsy value: Jan 27 10:39:43 volumio volumio[1033]: assert.ok(self.idling) Jan 27 10:39:44 volumio volumio[1033]: info: MPD running with PID1294 Jan 27 10:39:44 volumio volumio[1033]: ,establishing connection Jan 27 10:39:44 volumio volumio[1033]: error: updateQueue error: null Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 10:39:44 volumio volumio[1033]: info: Discovery: Getting this device information Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 27 10:39:44 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 10:39:44 volumio volumio-remote-updater[645]: [2026-01-27 10:39:44] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1769477981 101 Jan 27 10:39:44 volumio volumio[1033]: 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: 3 Jan 27 10:39:44 volumio volumio[1033]: verbose: New Socket.io Connection to 192.168.0.226:3000 from 192.168.0.130 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Jan 27 10:39:44 volumio volumio[1033]: error: updateQueue error: null Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 10:39:44 volumio volumio[1033]: info: Discovery: Getting this device information Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 27 10:39:44 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 10:39:44 volumio volumio[1033]: verbose: New Socket.io Connection to 192.168.0.226:3000 from 192.168.0.130 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 27 10:39:44 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 27 10:39:44 volumio sudo[1399]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 27 10:39:44 volumio sudo[1399]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:44 volumio sudo[1404]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 27 10:39:44 volumio sudo[1404]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:44 volumio sudo[1406]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 27 10:39:44 volumio sudo[1406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:44 volumio sudo[1399]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:44 volumio sudo[1404]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:44 volumio sudo[1424]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 27 10:39:44 volumio sudo[1424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:44 volumio sudo[1406]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:44 volumio sudo[1412]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 27 10:39:44 volumio sudo[1416]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 27 10:39:44 volumio sudo[1416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:44 volumio sudo[1412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:44 volumio sudo[1424]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:44 volumio sudo[1416]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:44 volumio sudo[1412]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 10:39:44 volumio volumio[1033]: info: Discovery: Getting this device information Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 27 10:39:44 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 10:39:44 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 27 10:39:44 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 27 10:39:45 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 10:39:45 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 10:39:45 volumio volumio[1033]: info: Discovery: Getting this device information Jan 27 10:39:45 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 27 10:39:45 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 27 10:39:45 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 10:39:45 volumio volumio[1033]: verbose: New Socket.io Connection to 192.168.0.226:3000 from 192.168.0.130 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Jan 27 10:39:45 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 27 10:39:45 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 27 10:39:45 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Jan 27 10:39:45 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 27 10:39:45 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 27 10:39:45 volumio sudo[1375]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:45 volumio volumio[1033]: info: Upmpdcli Daemon Started Jan 27 10:39:46 volumio sudo[1462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 27 10:39:46 volumio sudo[1462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:46 volumio sudo[1464]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 27 10:39:46 volumio sudo[1464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:46 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 27 10:39:46 volumio mpd_monitor.sh[1467]: MPD Monitor Service: Starting MPD Monitor Service Jan 27 10:39:46 volumio sudo[1462]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:46 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 27 10:39:46 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 27 10:39:46 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 27 10:39:46 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 27 10:39:46 volumio mpd_monitor.sh[1470]: MPD Monitor Service: Starting MPD Monitor Service Jan 27 10:39:46 volumio volumio[1033]: info: Successfully started MPD Monitor Jan 27 10:39:46 volumio sudo[1464]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:46 volumio volumio[1033]: info: Successfully started MPD Monitor Jan 27 10:39:47 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 27 10:39:47 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 27 10:39:48 volumio sudo[1481]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 27 10:39:48 volumio sudo[1481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:48 volumio sudo[1480]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 27 10:39:48 volumio sudo[1480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:48 volumio sudo[1480]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:48 volumio sudo[1481]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:48 volumio volumio[1033]: verbose: New Socket.io Connection to 192.168.0.226 from 192.168.0.130 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 4 Jan 27 10:39:48 volumio volumio[1033]: info: CoreCommandRouter::volumioGetVisibleSources Jan 27 10:39:48 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 10:39:48 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 27 10:39:48 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 27 10:39:49 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 27 10:39:49 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 27 10:39:49 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 27 10:39:49 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 27 10:39:49 volumio volumio[1033]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 27 10:39:55 volumio sudo[1502]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 27 10:39:55 volumio sudo[1504]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 27 10:39:55 volumio sudo[1504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:55 volumio sudo[1502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:39:55 volumio sudo[1502]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:55 volumio sudo[1504]: pam_unix(sudo:session): session closed for user root Jan 27 10:39:59 volumio volumio[1033]: verbose: New Socket.io Connection to 192.168.0.226 from 192.168.0.130 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 4 Jan 27 10:40:03 volumio volumio[1033]: verbose: New Socket.io Connection to 192.168.0.226 from 192.168.0.130 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 5 Jan 27 10:40:04 volumio volumio[1033]: error: MyVolumio Plugin failed to start in a timely fashion Jan 27 10:40:04 volumio volumio[1033]: [Metrics] CommandRouter: 39s 915.63ms Jan 27 10:40:04 volumio volumio[1033]: info: CoreCommandRouter::volumiosetStartupVolume Jan 27 10:40:04 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 10:40:04 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 10:40:04 volumio volumio[1033]: info: CoreCommandRouter::Close All Modals sent Jan 27 10:40:04 volumio volumio[1033]: info: CoreCommandRouter::Close All Modals sent Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 27 10:40:05 volumio volumio[1033]: info: Adding plugin bluetooth to MyMusic Plugins Jan 27 10:40:05 volumio volumio[1033]: info: Adding plugin multiroom to MyMusic Plugins Jan 27 10:40:05 volumio volumio[1033]: info: Adding plugin metavolumio to MyMusic Plugins Jan 27 10:40:05 volumio volumio[1033]: info: Adding plugin cd_controller to MyMusic Plugins Jan 27 10:40:05 volumio volumio[1033]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 27 10:40:05 volumio volumio[1033]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 27 10:40:05 volumio volumio[1033]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 27 10:40:05 volumio volumio[1033]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 27 10:40:10 volumio upmpdcli[1542]: writing RSA key Jan 27 10:40:10 volumio volumio[1033]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 27 10:40:10 volumio volumio[1033]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 27 10:40:10 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 10:40:10 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 10:40:10 volumio volumio[1033]: info: Starting MyVolumio Remote Streaming Endpoints Jan 27 10:40:10 volumio volumio[1033]: info: MyVolumio login type: Token Jan 27 10:40:10 volumio volumio[1033]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 27 10:40:10 volumio volumio[1033]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 27 10:40:13 volumio volumio[1033]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 27 10:40:13 volumio volumio[1033]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 27 10:40:13 volumio volumio[1033]: info: Streaming services startup Jan 27 10:40:13 volumio volumio[1033]: info: Starting Streaming Daemon Jan 27 10:40:13 volumio volumio[1033]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 27 10:40:13 volumio sudo[1554]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 27 10:40:13 volumio sudo[1554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 10:40:13 volumio volumio[1033]: verbose: New Socket.io Connection to 192.168.0.226 from 192.168.0.130 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 6 Jan 27 10:40:13 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Jan 27 10:40:13 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jan 27 10:40:13 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 27 10:40:13 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 10:40:13 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Jan 27 10:40:13 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 27 10:40:13 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Jan 27 10:40:13 volumio sudo[1554]: pam_unix(sudo:session): session closed for user root Jan 27 10:40:14 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 27 10:40:14 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 27 10:40:14 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 27 10:40:14 volumio volumio[1033]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 10:40:14 volumio volumio[1033]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Jan 27 10:40:14 volumio volumio[1033]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Jan 27 10:40:14 volumio volumio[1033]: at new NodeError (node:internal/errors:405:5) Jan 27 10:40:14 volumio volumio[1033]: at assert (node:internal/assert:14:11) Jan 27 10:40:14 volumio volumio[1033]: at internalConnectMultiple (node:net:1118:3) Jan 27 10:40:14 volumio volumio[1033]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Jan 27 10:40:14 volumio volumio[1033]: at listOnTimeout (node:internal/timers:575:11) Jan 27 10:40:14 volumio volumio[1033]: at process.processTimers (node:internal/timers:514:7) { Jan 27 10:40:14 volumio volumio[1033]: code: 'ERR_INTERNAL_ASSERTION' Jan 27 10:40:14 volumio volumio[1033]: } Jan 27 10:40:14 volumio volumio[1033]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 10:40:15 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 27 10:40:15 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 27 10:40:15 volumio systemd[1]: setdatetime-helper.service: Consumed 1.058s CPU time. Jan 27 10:40:17 volumio sudo[1605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-27 10:39' Jan 27 10:40:17 volumio sudo[1605]: 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"