Dec 07 15:41:10 volumio-zero2 ntpd[857]: CLOCK: time stepped by 25.949402 Dec 07 15:41:10 volumio-zero2 ntpd[857]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 07 15:41:12 volumio-zero2 volumio[1084]: info: ------------------------------------------- Dec 07 15:41:12 volumio-zero2 volumio[1084]: info: ----- Volumio3 ---- Dec 07 15:41:12 volumio-zero2 volumio[1084]: info: ------------------------------------------- Dec 07 15:41:12 volumio-zero2 volumio[1084]: info: ----- System startup ---- Dec 07 15:41:12 volumio-zero2 volumio[1084]: info: ------------------------------------------- Dec 07 15:41:12 volumio-zero2 systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 07 15:41:13 volumio-zero2 volumio[1084]: info: MYVOLUMIO Environment detected Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: Plugin folders cleanup Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: Scanning into folder /volumio/app/plugins/ Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: Scanning category audio_interface Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: Scanning category miscellanea Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: Scanning category music_service Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: Scanning category plugins.json Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: Scanning category system_controller Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: Scanning category user_interface Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: Scanning into folder /data/plugins/ Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: Scanning category audio_interface Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: Scanning category music_service Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: Plugin folders cleanup completed Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: ------------------------------------------- Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: ----- Core plugins startup ---- Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: ------------------------------------------- Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: Loading plugins from folder /volumio/app/plugins/ Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: Adding plugin upnp to MyMusic Plugins Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: Loading plugins from folder /data/plugins/ Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: Loading plugin "system"... Dec 07 15:41:14 volumio-zero2 volumio[1084]: info: Loading plugin "appearance"... Dec 07 15:41:16 volumio-zero2 volumio[1084]: info: Loading plugin "network"... Dec 07 15:41:16 volumio-zero2 volumio[1084]: info: Refreshing Cached IP Addresses Dec 07 15:41:16 volumio-zero2 sudo[1160]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 15:41:16 volumio-zero2 sudo[1160]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:41:16 volumio-zero2 volumio[1084]: info: Loading plugin "services"... Dec 07 15:41:16 volumio-zero2 volumio[1084]: info: Loading plugin "alsa_controller"... Dec 07 15:41:16 volumio-zero2 sudo[1159]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 07 15:41:16 volumio-zero2 sudo[1160]: pam_unix(sudo:session): session closed for user root Dec 07 15:41:16 volumio-zero2 sudo[1159]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:41:16 volumio-zero2 sudo[1167]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 07 15:41:16 volumio-zero2 sudo[1167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:41:16 volumio-zero2 sudo[1159]: pam_unix(sudo:session): session closed for user root Dec 07 15:41:16 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 15:41:16 volumio-zero2 volumio[1084]: info: Loading plugin "wizard"... Dec 07 15:41:16 volumio-zero2 volumio[1084]: info: Loading plugin "networkfs"... Dec 07 15:41:17 volumio-zero2 volumio[1084]: info: Starting Udev Watcher for removable devices Dec 07 15:41:17 volumio-zero2 sudo[1191]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=volumioadmin,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,noserverino //192.168.178.1/nas/PNY-USB3-0FD-00 /mnt/NAS/Nas Dec 07 15:41:17 volumio-zero2 sudo[1191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:41:17 volumio-zero2 volumio[1084]: info: Ignoring mount for partition: boot Dec 07 15:41:17 volumio-zero2 volumio[1084]: info: Ignoring mount for partition: volumio Dec 07 15:41:17 volumio-zero2 volumio[1084]: info: Ignoring mount for partition: volumio_data Dec 07 15:41:17 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 07 15:41:17 volumio-zero2 volumio[1084]: info: Loading plugin "volumio_command_line_client"... Dec 07 15:41:17 volumio-zero2 volumio[1084]: info: Loading plugin "upnp"... Dec 07 15:41:17 volumio-zero2 kernel: netfs: FS-Cache loaded Dec 07 15:41:17 volumio-zero2 volumio[1084]: info: [1765118477287] Starting Upmpd Daemon Dec 07 15:41:17 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 07 15:41:17 volumio-zero2 volumio[1084]: info: Loading plugin "my_music"... Dec 07 15:41:17 volumio-zero2 volumio[1084]: info: Loading plugin "mpd"... Dec 07 15:41:17 volumio-zero2 kernel: Key type cifs.spnego registered Dec 07 15:41:17 volumio-zero2 kernel: Key type cifs.idmap registered Dec 07 15:41:17 volumio-zero2 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. Dec 07 15:41:17 volumio-zero2 kernel: CIFS: Attempting to mount //192.168.178.1/nas/PNY-USB3-0FD-00 Dec 07 15:41:17 volumio-zero2 sudo[1167]: pam_unix(sudo:session): session closed for user root Dec 07 15:41:18 volumio-zero2 sudo[1191]: pam_unix(sudo:session): session closed for user root Dec 07 15:41:18 volumio-zero2 volumio[1084]: info: Loading plugin "upnp_browser"... Dec 07 15:41:18 volumio-zero2 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 07 15:41:19 volumio-zero2 volumio-remote-updater[560]: [2025-12-07 15:41:19] [connect] Successful connection Dec 07 15:41:21 volumio-zero2 volumio[1084]: info: Loading plugin "alarm-clock"... Dec 07 15:41:21 volumio-zero2 volumio[1084]: info: Loading plugin "airplay_emulation"... Dec 07 15:41:21 volumio-zero2 volumio[1084]: info: Starting Shairport Sync Dec 07 15:41:21 volumio-zero2 volumio[1084]: info: Loading plugin "last_100"... Dec 07 15:41:22 volumio-zero2 volumio[1084]: info: Loading plugin "webradio"... Dec 07 15:41:22 volumio-zero2 volumio[1084]: info: Loading plugin "i2s_dacs"... Dec 07 15:41:22 volumio-zero2 volumio[1084]: info: Loading plugin "volumiodiscovery"... Dec 07 15:41:22 volumio-zero2 volumio[1084]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 07 15:41:22 volumio-zero2 volumio[1084]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 07 15:41:22 volumio-zero2 volumio[1084]: *** WARNING *** For more information see Dec 07 15:41:22 volumio-zero2 volumio[1084]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 07 15:41:22 volumio-zero2 volumio[1084]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 07 15:41:22 volumio-zero2 volumio[1084]: *** WARNING *** For more information see Dec 07 15:41:22 volumio-zero2 node[1084]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 07 15:41:22 volumio-zero2 node[1084]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 07 15:41:22 volumio-zero2 node[1084]: *** WARNING *** For more information see Dec 07 15:41:22 volumio-zero2 node[1084]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 07 15:41:22 volumio-zero2 node[1084]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 07 15:41:22 volumio-zero2 node[1084]: *** WARNING *** For more information see Dec 07 15:41:22 volumio-zero2 volumio[1084]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 07 15:41:22 volumio-zero2 volumio[1084]: info: Discovery: Started advertising with name: Volumio-Zero2 Dec 07 15:41:22 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 07 15:41:22 volumio-zero2 volumio[1084]: info: Loading plugin "youtube2"... Dec 07 15:41:24 volumio-zero2 volumio[1084]: info: Loading plugin "outputs"... Dec 07 15:41:24 volumio-zero2 volumio[1084]: info: Loading plugin "albumart"... Dec 07 15:41:24 volumio-zero2 volumio[1084]: info: Plugin example_plugin is not enabled Dec 07 15:41:24 volumio-zero2 volumio[1084]: info: Loading plugin "inputs"... Dec 07 15:41:24 volumio-zero2 volumio[1084]: info: Loading plugin "updater_comm"... Dec 07 15:41:24 volumio-zero2 volumio[1084]: info: Plugin mpdemulation is not enabled Dec 07 15:41:24 volumio-zero2 volumio[1084]: info: Loading plugin "rest_api"... Dec 07 15:41:24 volumio-zero2 volumio[1084]: info: Loading plugin "websocket"... Dec 07 15:41:24 volumio-zero2 volumio[1084]: info: Starting Socket.io Server version 1.7.4 Dec 07 15:41:24 volumio-zero2 volumio[1084]: info: Loading plugin "fusiondsp"... Dec 07 15:41:26 volumio-zero2 volumio[1084]: info: Applying required configuration parameters for plugin fusiondsp Dec 07 15:41:26 volumio-zero2 volumio[1232]: Forking 3 albumart workers Dec 07 15:41:27 volumio-zero2 volumio[1084]: info: Loading i18n strings for locale en Dec 07 15:41:28 volumio-zero2 volumio[1084]: Updating browse sources language Dec 07 15:41:28 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 15:41:29 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 15:41:29 volumio-zero2 volumio[1084]: info: CoreCommandRouter::initPlayerControls Dec 07 15:41:29 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:29 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:29 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:29 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:29 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:29 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:29 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:29 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:29 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 15:41:29 volumio-zero2 volumio[1084]: Express server listening on port 3000 Dec 07 15:41:29 volumio-zero2 volumio[1084]: [Metrics] WebUI: 19s 168.79ms Dec 07 15:41:30 volumio-zero2 volumio[1084]: info: CoreStateMachine::resetVolumioState Dec 07 15:41:30 volumio-zero2 volumio[1084]: info: CoreStateMachine::getcurrentVolume Dec 07 15:41:30 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioRetrievevolume Dec 07 15:41:30 volumio-zero2 sudo[1298]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 15:41:30 volumio-zero2 sudo[1296]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 07 15:41:30 volumio-zero2 sudo[1296]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:41:30 volumio-zero2 sudo[1298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:41:30 volumio-zero2 sudo[1296]: pam_unix(sudo:session): session closed for user root Dec 07 15:41:30 volumio-zero2 sudo[1298]: pam_unix(sudo:session): session closed for user root Dec 07 15:41:30 volumio-zero2 volumio[1084]: info: Volumio Network Manager: Network status updated: 2 Dec 07 15:41:31 volumio-zero2 volumio[1084]: verbose: New Socket.io Connection to 192.168.178.108 from 192.168.178.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 07 15:41:32 volumio-zero2 volumio[1084]: info: CoreStateMachine::pushState Dec 07 15:41:32 volumio-zero2 volumio[1084]: info: CorePlayQueue::getTrack 0 Dec 07 15:41:32 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 15:41:32 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioPushState Dec 07 15:41:32 volumio-zero2 volumio[1084]: info: CoreStateMachine::updateTrackBlock Dec 07 15:41:32 volumio-zero2 volumio[1084]: info: CorePlayQueue::getTrackBlock Dec 07 15:41:32 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioRetrievevolume Dec 07 15:41:32 volumio-zero2 volumio[1084]: verbose: New Socket.io Connection to 192.168.178.108 from 192.168.178.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 07 15:41:32 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 15:41:32 volumio-zero2 volumio[1084]: info: Reloading queue from file Dec 07 15:41:33 volumio-zero2 volumio[1084]: info: CoreStateMachine::setRepeat null single undefined Dec 07 15:41:33 volumio-zero2 volumio[1084]: info: CoreStateMachine::pushState Dec 07 15:41:33 volumio-zero2 volumio[1084]: info: CorePlayQueue::getTrack 0 Dec 07 15:41:33 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 15:41:33 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioPushState Dec 07 15:41:33 volumio-zero2 volumio[1084]: info: CoreStateMachine::setRandom null Dec 07 15:41:33 volumio-zero2 volumio[1084]: info: CoreStateMachine::pushState Dec 07 15:41:33 volumio-zero2 volumio[1084]: info: CorePlayQueue::getTrack 0 Dec 07 15:41:33 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioPushState Dec 07 15:41:33 volumio-zero2 volumio[1084]: info: Setting Device type: Raspberry PI Dec 07 15:41:33 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioGetState Dec 07 15:41:33 volumio-zero2 volumio[1084]: info: CorePlayQueue::getTrack 0 Dec 07 15:41:34 volumio-zero2 volumio[1084]: info: Completed loading Core Plugins Dec 07 15:41:34 volumio-zero2 volumio[1084]: info: Preparing to generate the ALSA configuration file Dec 07 15:41:34 volumio-zero2 volumio-remote-updater[560]: [2025-12-07 15:41:34] [connect] Successful connection Dec 07 15:41:34 volumio-zero2 volumio[1084]: info: CoreStateMachine::pushState Dec 07 15:41:34 volumio-zero2 volumio[1084]: info: CorePlayQueue::getTrack 0 Dec 07 15:41:34 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioPushState Dec 07 15:41:34 volumio-zero2 volumio[1084]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 07 15:41:34 volumio-zero2 volumio[1084]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Dec 07 15:41:34 volumio-zero2 volumio[1084]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 07 15:41:34 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioGetQueue Dec 07 15:41:34 volumio-zero2 volumio[1084]: info: CoreStateMachine::getQueue Dec 07 15:41:34 volumio-zero2 volumio[1084]: info: CorePlayQueue::getQueue Dec 07 15:41:34 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Dec 07 15:41:34 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Dec 07 15:41:34 volumio-zero2 volumio[1084]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Dec 07 15:41:34 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Dec 07 15:41:34 volumio-zero2 volumio[1084]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Dec 07 15:41:34 volumio-zero2 volumio[1084]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Dec 07 15:41:34 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Dec 07 15:41:34 volumio-zero2 volumio[1084]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 07 15:41:34 volumio-zero2 volumio[1084]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 07 15:41:35 volumio-zero2 volumio[1084]: info: Reading ALSA contributions from plugins. Dec 07 15:41:35 volumio-zero2 volumio[1084]: info: Discovery: adding 5995d0dc-0cf1-4db8-8c47-7f49f885be57 Dec 07 15:41:35 volumio-zero2 volumio[1084]: info: Discovery: Found device Volumio-Zero2 Dec 07 15:41:35 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioGetState Dec 07 15:41:35 volumio-zero2 volumio[1084]: info: CorePlayQueue::getTrack 0 Dec 07 15:41:35 volumio-zero2 volumio-remote-updater[560]: [2025-12-07 15:41:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1765118494 101 Dec 07 15:41:35 volumio-zero2 sudo[1319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 07 15:41:35 volumio-zero2 sudo[1319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:41:35 volumio-zero2 volumio[1084]: 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 Dec 07 15:41:35 volumio-zero2 volumio[1084]: info: Discovery: this is already registered, 5995d0dc-0cf1-4db8-8c47-7f49f885be57 Dec 07 15:41:35 volumio-zero2 volumio[1084]: info: Discovery: Found device Volumio-Zero2 Dec 07 15:41:35 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioGetState Dec 07 15:41:35 volumio-zero2 volumio[1084]: info: CorePlayQueue::getTrack 0 Dec 07 15:41:35 volumio-zero2 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 07 15:41:35 volumio-zero2 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 07 15:41:35 volumio-zero2 sudo[1319]: pam_unix(sudo:session): session closed for user root Dec 07 15:41:35 volumio-zero2 volumio[1084]: info: Upmpdcli Daemon Started Dec 07 15:41:36 volumio-zero2 volumio[1084]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Dec 07 15:41:36 volumio-zero2 volumio[1084]: info: Asound.conf file unchanged, so no further update is needed Dec 07 15:41:36 volumio-zero2 volumio[1084]: info: Output device has changed, restarting MPD Dec 07 15:41:36 volumio-zero2 volumio[1084]: info: Output device has changed, restarting Shairport Sync Dec 07 15:41:36 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:36 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 15:41:36 volumio-zero2 sudo[1348]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 07 15:41:36 volumio-zero2 sudo[1348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:41:36 volumio-zero2 sudo[1348]: pam_unix(sudo:session): session closed for user root Dec 07 15:41:36 volumio-zero2 sudo[1350]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 07 15:41:36 volumio-zero2 sudo[1350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:41:36 volumio-zero2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 07 15:41:36 volumio-zero2 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 07 15:41:36 volumio-zero2 volumio[1084]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 07 15:41:36 volumio-zero2 volumio[1084]: info: ___________ START PLUGINS ___________ Dec 07 15:41:36 volumio-zero2 volumio[1084]: info: ControllerMpd::onStart: Initializing MPD Dec 07 15:41:36 volumio-zero2 volumio[1084]: info: Creating MPD Configuration file Dec 07 15:41:37 volumio-zero2 sudo[1360]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 07 15:41:37 volumio-zero2 sudo[1360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 07 15:41:37 volumio-zero2 sudo[1364]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 07 15:41:37 volumio-zero2 sudo[1360]: pam_unix(sudo:session): session closed for user root Dec 07 15:41:37 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 15:41:37 volumio-zero2 sudo[1363]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 07 15:41:37 volumio-zero2 sudo[1363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:41:37 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 15:41:37 volumio-zero2 volumio[1084]: info: [1765118497309] CoreMusicLibrary::Adding element Media Servers Dec 07 15:41:37 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 15:41:37 volumio-zero2 sudo[1368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 07 15:41:37 volumio-zero2 sudo[1368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:41:37 volumio-zero2 sudo[1368]: pam_unix(sudo:session): session closed for user root Dec 07 15:41:37 volumio-zero2 systemd[1]: mpd.service: Deactivated successfully. Dec 07 15:41:37 volumio-zero2 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 07 15:41:37 volumio-zero2 systemd[1]: mpd.socket: Deactivated successfully. Dec 07 15:41:37 volumio-zero2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 07 15:41:37 volumio-zero2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 07 15:41:37 volumio-zero2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 07 15:41:37 volumio-zero2 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 07 15:41:37 volumio-zero2 sudo[1388]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 07 15:41:37 volumio-zero2 sudo[1388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 07 15:41:37 volumio-zero2 sudo[1390]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 07 15:41:37 volumio-zero2 sudo[1388]: pam_unix(sudo:session): session closed for user root Dec 07 15:41:38 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:38 volumio-zero2 volumio[1247]: Starting albumart workers Dec 07 15:41:38 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 15:41:38 volumio-zero2 systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 07 15:41:38 volumio-zero2 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 07 15:41:38 volumio-zero2 systemd[1]: setdatetime-helper.service: Consumed 1.321s CPU time. Dec 07 15:41:38 volumio-zero2 volumio[1084]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 07 15:41:38 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:38 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 15:41:38 volumio-zero2 volumio[1084]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 07 15:41:38 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 15:41:38 volumio-zero2 volumio[1084]: info: [1765118498819] CoreMusicLibrary::Adding element Last_100 Dec 07 15:41:38 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 15:41:38 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 15:41:38 volumio-zero2 volumio[1084]: info: [1765118498882] CoreMusicLibrary::Adding element Webradio Dec 07 15:41:38 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 15:41:38 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 15:41:39 volumio-zero2 volumio[1084]: info: Initializing BBC Radios Dec 07 15:41:39 volumio-zero2 volumio[1252]: Starting albumart workers Dec 07 15:41:39 volumio-zero2 volumio[1084]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Dec 07 15:41:39 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 15:41:39 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 15:41:39 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 15:41:39 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 15:41:39 volumio-zero2 volumio[1084]: info: [1765118499539] CoreMusicLibrary::Adding element YouTube2 Dec 07 15:41:39 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 15:41:39 volumio-zero2 volumio[1084]: Cannot find translation for source YouTube2 Dec 07 15:41:40 volumio-zero2 volumio[1242]: Starting albumart workers Dec 07 15:41:40 volumio-zero2 volumio[1084]: info: FusionDsp - CamillaDSP binary found. Dec 07 15:41:40 volumio-zero2 volumio[1084]: info: Loading i18n strings for locale en Dec 07 15:41:40 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 07 15:41:40 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:40 volumio-zero2 volumio[1084]: info: FusionDsp - mixtype--------------------- Software Dec 07 15:41:40 volumio-zero2 volumio[1084]: info: Volumio Calling Home Dec 07 15:41:41 volumio-zero2 volumio[1084]: info: Preparing to generate the ALSA configuration file Dec 07 15:41:47 volumio-zero2 volumio[1084]: info: camilladsp service started and running in background, instance 1 Dec 07 15:41:47 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:47 volumio-zero2 volumio[1084]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Dec 07 15:41:48 volumio-zero2 volumio[1084]: info: FusionDsp loaded Dec 07 15:41:48 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 15:41:48 volumio-zero2 volumio[1084]: error: FusionDsp - Socket connection not established Dec 07 15:41:48 volumio-zero2 volumio[1084]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 07 15:41:48 volumio-zero2 volumio[1084]: info: Adding Signal Path Element [object Object] Dec 07 15:41:48 volumio-zero2 volumio[1084]: info: Adding fusiondspeq DSP Signal Path Element Dec 07 15:41:48 volumio-zero2 volumio[1084]: info: FusionDsp - ---- installed callbackRead Dec 07 15:41:48 volumio-zero2 volumio[1084]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 07 15:41:48 volumio-zero2 volumio[1084]: info: FusionDsp - Loudness is ON true Dec 07 15:41:48 volumio-zero2 volumio[1084]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 07 15:41:48 volumio-zero2 volumio[1084]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 07 15:41:48 volumio-zero2 volumio[1084]: info: Reading ALSA contributions from plugins. Dec 07 15:41:48 volumio-zero2 mpd[1391]: 2025-12-07T15:41:48 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 07 15:41:48 volumio-zero2 volumio[1084]: info: MPD Permissions set Dec 07 15:41:48 volumio-zero2 volumio[1084]: info: MPD Permissions set Dec 07 15:41:48 volumio-zero2 systemd[1]: Started mpd.service - Music Player Daemon. Dec 07 15:41:49 volumio-zero2 sudo[1363]: pam_unix(sudo:session): session closed for user root Dec 07 15:41:49 volumio-zero2 volumio[1084]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 07 15:41:49 volumio-zero2 sudo[1460]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 07 15:41:49 volumio-zero2 sudo[1460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:41:49 volumio-zero2 sudo[1350]: pam_unix(sudo:session): session closed for user root Dec 07 15:41:49 volumio-zero2 volumio[1084]: verbose: New Socket.io Connection to 192.168.178.108 from 192.168.178.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 07 15:41:49 volumio-zero2 systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Dec 07 15:41:49 volumio-zero2 sudo[1460]: pam_unix(sudo:session): session closed for user root Dec 07 15:41:50 volumio-zero2 volumio[1084]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 07 15:41:50 volumio-zero2 volumio[1084]: info: Completed starting Core Plugins Dec 07 15:41:50 volumio-zero2 volumio[1084]: info: ------------------------------------------- Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: ----- MyVolumio plugins startup ---- Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: ------------------------------------------- Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: Volumio called home Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:51 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:53 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 15:41:53 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:53 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 07 15:41:53 volumio-zero2 volumio[1084]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioGetVisibleSources Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioGetState Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: CorePlayQueue::getTrack 0 Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioGetState Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: CorePlayQueue::getTrack 0 Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: Received Get System Info Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: Discovery: Getting this device information Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioGetState Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: CorePlayQueue::getTrack 0 Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioGetState Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: CorePlayQueue::getTrack 0 Dec 07 15:41:54 volumio-zero2 sudo[1493]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 07 15:41:54 volumio-zero2 sudo[1493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:41:54 volumio-zero2 sudo[1495]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 07 15:41:54 volumio-zero2 sudo[1495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:41:54 volumio-zero2 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: Listing playlists Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioGetQueue Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: CoreStateMachine::getQueue Dec 07 15:41:54 volumio-zero2 volumio[1084]: info: CorePlayQueue::getQueue Dec 07 15:41:54 volumio-zero2 mpd_monitor.sh[1499]: MPD Monitor Service: Starting MPD Monitor Service Dec 07 15:41:54 volumio-zero2 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 07 15:41:54 volumio-zero2 sudo[1493]: pam_unix(sudo:session): session closed for user root Dec 07 15:41:54 volumio-zero2 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 07 15:41:54 volumio-zero2 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 07 15:41:54 volumio-zero2 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 07 15:41:54 volumio-zero2 sudo[1495]: pam_unix(sudo:session): session closed for user root Dec 07 15:41:54 volumio-zero2 mpd_monitor.sh[1502]: MPD Monitor Service: Starting MPD Monitor Service Dec 07 15:41:55 volumio-zero2 volumio[1084]: error: MPD error: The expression evaluated to a falsy value: Dec 07 15:41:55 volumio-zero2 volumio[1084]: assert.ok(self.idling) Dec 07 15:41:55 volumio-zero2 volumio[1084]: error: The expression evaluated to a falsy value: Dec 07 15:41:55 volumio-zero2 volumio[1084]: assert.ok(self.idling) Dec 07 15:41:55 volumio-zero2 volumio[1084]: info: MPD running with PID1391 Dec 07 15:41:55 volumio-zero2 volumio[1084]: ,establishing connection Dec 07 15:41:56 volumio-zero2 volumio[1084]: error: updateQueue error: null Dec 07 15:41:57 volumio-zero2 volumio[1084]: info: Successfully started MPD Monitor Dec 07 15:41:57 volumio-zero2 volumio[1084]: info: Successfully started MPD Monitor Dec 07 15:41:57 volumio-zero2 volumio[1084]: info: Starting Shairport Sync Dec 07 15:41:57 volumio-zero2 volumio[1084]: info: Starting Shairport Sync Dec 07 15:41:57 volumio-zero2 volumio[1084]: info: Starting Shairport Sync Dec 07 15:41:57 volumio-zero2 sudo[1512]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 07 15:41:57 volumio-zero2 sudo[1512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:41:57 volumio-zero2 sudo[1509]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 07 15:41:57 volumio-zero2 sudo[1509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:41:57 volumio-zero2 sudo[1510]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 07 15:41:57 volumio-zero2 sudo[1510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:41:57 volumio-zero2 volumio[1084]: error: updateQueue error: null Dec 07 15:41:57 volumio-zero2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 07 15:41:57 volumio-zero2 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 07 15:41:57 volumio-zero2 volumio[1084]: info: Asound.conf file unchanged, so no further update is needed Dec 07 15:41:57 volumio-zero2 volumio[1084]: info: Output device has changed, restarting MPD Dec 07 15:41:57 volumio-zero2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 07 15:41:57 volumio-zero2 systemd[1]: shairport-sync.service: Consumed 1.812s CPU time. Dec 07 15:41:58 volumio-zero2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 07 15:41:58 volumio-zero2 sudo[1510]: pam_unix(sudo:session): session closed for user root Dec 07 15:41:58 volumio-zero2 sudo[1512]: pam_unix(sudo:session): session closed for user root Dec 07 15:41:58 volumio-zero2 sudo[1509]: pam_unix(sudo:session): session closed for user root Dec 07 15:41:58 volumio-zero2 volumio[1084]: info: Output device has changed, restarting Shairport Sync Dec 07 15:41:58 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:41:58 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 15:41:58 volumio-zero2 sudo[1519]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 07 15:41:58 volumio-zero2 sudo[1519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:41:58 volumio-zero2 sudo[1522]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 07 15:41:58 volumio-zero2 sudo[1522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:41:58 volumio-zero2 sudo[1519]: pam_unix(sudo:session): session closed for user root Dec 07 15:41:58 volumio-zero2 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 07 15:41:58 volumio-zero2 volumio[1084]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 07 15:41:59 volumio-zero2 volumio[1084]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 15:41:59 volumio-zero2 volumio[1084]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 15:41:59 volumio-zero2 volumio[1084]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 15:41:59 volumio-zero2 volumio[1084]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 15:41:59 volumio-zero2 volumio[1084]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 15:42:00 volumio-zero2 systemd[1]: mpd.service: Deactivated successfully. Dec 07 15:42:00 volumio-zero2 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 07 15:42:00 volumio-zero2 systemd[1]: mpd.service: Consumed 8.004s CPU time. Dec 07 15:42:00 volumio-zero2 systemd[1]: mpd.socket: Deactivated successfully. Dec 07 15:42:00 volumio-zero2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 07 15:42:00 volumio-zero2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 07 15:42:00 volumio-zero2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 07 15:42:00 volumio-zero2 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 07 15:42:00 volumio-zero2 sudo[1558]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 07 15:42:00 volumio-zero2 sudo[1558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 07 15:42:00 volumio-zero2 sudo[1558]: pam_unix(sudo:session): session closed for user root Dec 07 15:42:01 volumio-zero2 volumio[1084]: info: MPD Permissions set Dec 07 15:42:01 volumio-zero2 volumio[1084]: info: Shairport-Sync Started Dec 07 15:42:01 volumio-zero2 volumio[1084]: Error adding Membership: Error: addMembership EINVAL Dec 07 15:42:01 volumio-zero2 volumio[1084]: info: Shairport-Sync Started Dec 07 15:42:01 volumio-zero2 volumio[1084]: info: Shairport-Sync Started Dec 07 15:42:07 volumio-zero2 mpd[1560]: 2025-12-07T15:42:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 07 15:42:07 volumio-zero2 systemd[1]: Started mpd.service - Music Player Daemon. Dec 07 15:42:07 volumio-zero2 sudo[1522]: pam_unix(sudo:session): session closed for user root Dec 07 15:42:09 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:42:09 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:42:09 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:42:09 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:42:09 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:42:09 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:42:09 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:42:09 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:42:09 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 15:42:09 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:42:09 volumio-zero2 volumio[1084]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Dec 07 15:42:09 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 15:42:09 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 15:42:09 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 15:42:09 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 07 15:42:12 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumioGetState Dec 07 15:42:12 volumio-zero2 volumio[1084]: info: CorePlayQueue::getTrack 0 Dec 07 15:42:13 volumio-zero2 volumio[1084]: error: MyVolumio Plugin failed to start in a timely fashion Dec 07 15:42:13 volumio-zero2 volumio[1084]: [Metrics] CommandRouter: 60s 868.16ms Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: CoreCommandRouter::volumiosetStartupVolume Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: CoreCommandRouter::Close All Modals sent Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: CoreCommandRouter::Close All Modals sent Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: Adding plugin bluetooth to MyMusic Plugins Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: Adding plugin multiroom to MyMusic Plugins Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: Adding plugin metavolumio to MyMusic Plugins Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: Adding plugin cd_controller to MyMusic Plugins Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 07 15:42:13 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 07 15:42:30 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 07 15:42:30 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 07 15:42:30 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 15:42:30 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 15:42:30 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 15:42:30 volumio-zero2 volumio[1084]: info: Starting MyVolumio Remote Streaming Endpoints Dec 07 15:42:30 volumio-zero2 volumio[1084]: info: MyVolumio login type: Token Dec 07 15:42:30 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 07 15:42:30 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 07 15:42:37 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 07 15:42:37 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 07 15:42:37 volumio-zero2 volumio[1084]: info: Streaming services startup Dec 07 15:42:37 volumio-zero2 volumio[1084]: info: Starting Streaming Daemon Dec 07 15:42:37 volumio-zero2 volumio[1084]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 07 15:42:37 volumio-zero2 sudo[1630]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 07 15:42:37 volumio-zero2 sudo[1630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:42:37 volumio-zero2 sudo[1630]: pam_unix(sudo:session): session closed for user root Dec 07 15:42:37 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 07 15:42:37 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 07 15:42:37 volumio-zero2 volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 07 15:42:38 volumio-zero2 volumio[1084]: info: BOOT COMPLETED Dec 07 15:42:38 volumio-zero2 volumio[1084]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 15:42:38 volumio-zero2 sudo[1637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 07 15:42:38 volumio-zero2 sudo[1637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 15:42:38 volumio-zero2 volumio[1084]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Dec 07 15:42:38 volumio-zero2 volumio[1084]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Dec 07 15:42:38 volumio-zero2 volumio[1084]: at new NodeError (node:internal/errors:405:5) Dec 07 15:42:38 volumio-zero2 volumio[1084]: at assert (node:internal/assert:14:11) Dec 07 15:42:38 volumio-zero2 volumio[1084]: at internalConnectMultiple (node:net:1118:3) Dec 07 15:42:38 volumio-zero2 volumio[1084]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Dec 07 15:42:38 volumio-zero2 volumio[1084]: at listOnTimeout (node:internal/timers:575:11) Dec 07 15:42:38 volumio-zero2 volumio[1084]: at process.processTimers (node:internal/timers:514:7) { Dec 07 15:42:38 volumio-zero2 volumio[1084]: code: 'ERR_INTERNAL_ASSERTION' Dec 07 15:42:38 volumio-zero2 volumio[1084]: } Dec 07 15:42:38 volumio-zero2 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 07 15:42:38 volumio-zero2 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 07 15:42:38 volumio-zero2 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 07 15:42:38 volumio-zero2 volumio[1084]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 15:42:38 volumio-zero2 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 07 15:42:38 volumio-zero2 sudo[1637]: pam_unix(sudo:session): session closed for user root Dec 07 15:42:38 volumio-zero2 mpd_monitor.sh[1639]: MPD Monitor Service: Starting MPD Monitor Service Dec 07 15:42:49 volumio-zero2 sudo[1671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-07 15:41' Dec 07 15:42:49 volumio-zero2 sudo[1671]: 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="ec1ce6b3390e13e82b9cc0453f957c4f5298419e" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Nov 17 16:31:40 UTC 2025" VOLUMIO_VERSION="4.071" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5fbe1bcf6936184712979c0bfaf061f2"