Jan 27 11:26:00 volumio mpd[2138]: 2026-01-27T11:26:00 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 27 11:26:00 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 27 11:26:00 volumio sudo[2120]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:00 volumio sudo[2110]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:01 volumio volumio[1992]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 11:26:02 volumio volumio[1992]: Unhandled rejection Error: No sockets available, cannot start. Jan 27 11:26:02 volumio volumio[1992]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 27 11:26:02 volumio volumio[1992]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 27 11:26:02 volumio volumio[1992]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 27 11:26:02 volumio volumio[1992]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 27 11:26:02 volumio volumio[1992]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 27 11:26:02 volumio volumio[1992]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 27 11:26:02 volumio volumio[1992]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 27 11:26:02 volumio volumio[1992]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 27 11:26:02 volumio volumio[1992]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 27 11:26:02 volumio volumio[1992]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 27 11:26:02 volumio volumio[1992]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 27 11:26:02 volumio volumio[1992]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 27 11:26:02 volumio volumio[1992]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 27 11:26:02 volumio volumio[1992]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 27 11:26:02 volumio volumio[1992]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 27 11:26:02 volumio volumio[1992]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 27 11:26:02 volumio volumio[1992]: info: MPD Permissions set Jan 27 11:26:02 volumio volumio[1992]: info: MPD Permissions set Jan 27 11:26:02 volumio volumio[1992]: info: VolumeController:: Volume=100 Mute =false Jan 27 11:26:02 volumio volumio[1992]: info: CoreStateMachine::pushState Jan 27 11:26:02 volumio volumio[1992]: info: CorePlayQueue::getTrack 0 Jan 27 11:26:02 volumio volumio[1992]: info: CoreCommandRouter::volumioPushState Jan 27 11:26:02 volumio volumio[1992]: info: [squeezelite_mc] Server discovery started Jan 27 11:26:02 volumio volumio[1992]: info: [squeezelite_mc] Player finder started Jan 27 11:26:02 volumio volumio[1992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:02 volumio volumio[1992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:02 volumio volumio[1992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:02 volumio volumio[1992]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:26:02 volumio volumio[1992]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 27 11:26:02 volumio volumio[1992]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 11:26:02 volumio volumio[1992]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 27 11:26:02 volumio volumio[1992]: at doSend (node:dgram:717:16) Jan 27 11:26:02 volumio volumio[1992]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Jan 27 11:26:02 volumio volumio[1992]: at afterDns (node:dgram:663:5) Jan 27 11:26:02 volumio volumio[1992]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Jan 27 11:26:02 volumio volumio[1992]: errno: -101, Jan 27 11:26:02 volumio volumio[1992]: code: 'ENETUNREACH', Jan 27 11:26:02 volumio volumio[1992]: syscall: 'send', Jan 27 11:26:02 volumio volumio[1992]: address: '255.255.255.255', Jan 27 11:26:02 volumio volumio[1992]: port: 3483 Jan 27 11:26:02 volumio volumio[1992]: } Jan 27 11:26:02 volumio volumio[1992]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 11:26:03 volumio volumio[2076]: Starting albumart workers Jan 27 11:26:03 volumio volumio[2078]: Starting albumart workers Jan 27 11:26:03 volumio volumio[2077]: Starting albumart workers Jan 27 11:26:07 volumio sudo[2209]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-27 11:25' Jan 27 11:26:07 volumio sudo[2209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:07 volumio sudo[2209]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:08 volumio volumio-remote-updater[820]: [2026-01-27 11:26:08] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 27 11:26:08 volumio volumio-remote-updater[820]: [2026-01-27 11:26:08] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 27 11:26:08 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 27 11:26:08 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 27 11:26:08 volumio systemd[1]: volumio.service: Consumed 37.088s CPU time. Jan 27 11:26:08 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 27 11:26:08 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Jan 27 11:26:08 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Jan 27 11:26:08 volumio systemd[1]: volumio.service: Consumed 37.088s CPU time. Jan 27 11:26:08 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Jan 27 11:26:08 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 27 11:26:10 volumio volumio[2224]: info: ------------------------------------------- Jan 27 11:26:10 volumio volumio[2224]: info: ----- Volumio3 ---- Jan 27 11:26:10 volumio volumio[2224]: info: ------------------------------------------- Jan 27 11:26:10 volumio volumio[2224]: info: ----- System startup ---- Jan 27 11:26:10 volumio volumio[2224]: info: ------------------------------------------- Jan 27 11:26:11 volumio volumio[2224]: info: MYVOLUMIO Environment detected Jan 27 11:26:11 volumio volumio[2224]: info: Plugin folders cleanup Jan 27 11:26:11 volumio volumio[2224]: info: Scanning into folder /volumio/app/plugins/ Jan 27 11:26:11 volumio volumio[2224]: info: Scanning category audio_interface Jan 27 11:26:11 volumio volumio[2224]: info: Scanning category miscellanea Jan 27 11:26:11 volumio volumio[2224]: info: Scanning category music_service Jan 27 11:26:11 volumio volumio[2224]: info: Scanning category plugins.json Jan 27 11:26:11 volumio volumio[2224]: info: Scanning category system_controller Jan 27 11:26:11 volumio volumio[2224]: info: Scanning category user_interface Jan 27 11:26:11 volumio volumio[2224]: info: Scanning into folder /data/plugins/ Jan 27 11:26:11 volumio volumio[2224]: info: Scanning category music_service Jan 27 11:26:11 volumio volumio[2224]: info: Plugin folders cleanup completed Jan 27 11:26:11 volumio volumio[2224]: info: ------------------------------------------- Jan 27 11:26:11 volumio volumio[2224]: info: ----- Core plugins startup ---- Jan 27 11:26:11 volumio volumio[2224]: info: ------------------------------------------- Jan 27 11:26:11 volumio volumio[2224]: info: Loading plugins from folder /volumio/app/plugins/ Jan 27 11:26:11 volumio volumio[2224]: info: Adding plugin upnp to MyMusic Plugins Jan 27 11:26:11 volumio volumio[2224]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 27 11:26:11 volumio volumio[2224]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 27 11:26:11 volumio volumio[2224]: info: Loading plugins from folder /data/plugins/ Jan 27 11:26:11 volumio volumio[2224]: info: Loading plugin "system"... Jan 27 11:26:11 volumio volumio[2224]: info: Loading plugin "appearance"... Jan 27 11:26:12 volumio volumio[2224]: info: Loading plugin "network"... Jan 27 11:26:12 volumio volumio[2224]: info: Refreshing Cached IP Addresses Jan 27 11:26:12 volumio sudo[2252]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 27 11:26:12 volumio sudo[2254]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 27 11:26:12 volumio sudo[2254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:12 volumio sudo[2254]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:12 volumio sudo[2252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:12 volumio volumio[2224]: info: Loading plugin "services"... Jan 27 11:26:12 volumio sudo[2252]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:12 volumio volumio[2224]: info: Loading plugin "alsa_controller"... Jan 27 11:26:12 volumio sudo[2261]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 27 11:26:12 volumio sudo[2261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:12 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 27 11:26:12 volumio volumio[2224]: info: Loading plugin "wizard"... Jan 27 11:26:12 volumio volumio[2224]: info: Loading plugin "networkfs"... Jan 27 11:26:12 volumio volumio[2224]: info: Starting Udev Watcher for removable devices Jan 27 11:26:12 volumio volumio[2224]: info: Ignoring mount for partition: boot Jan 27 11:26:12 volumio volumio[2224]: info: Ignoring mount for partition: volumio Jan 27 11:26:12 volumio volumio[2224]: info: Ignoring mount for partition: volumio_data Jan 27 11:26:12 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 27 11:26:12 volumio volumio[2224]: info: Loading plugin "volumio_command_line_client"... Jan 27 11:26:12 volumio volumio[2224]: info: Loading plugin "upnp"... Jan 27 11:26:12 volumio volumio[2224]: info: [1769513172482] Starting Upmpd Daemon Jan 27 11:26:12 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 27 11:26:12 volumio volumio[2224]: info: Loading plugin "my_music"... Jan 27 11:26:12 volumio volumio[2224]: info: Loading plugin "mpd"... Jan 27 11:26:12 volumio volumio[2224]: info: Loading plugin "upnp_browser"... Jan 27 11:26:13 volumio volumio-remote-updater[820]: [2026-01-27 11:26:13] [connect] Successful connection Jan 27 11:26:13 volumio systemd[1]: nmbd.service: start operation timed out. Terminating. Jan 27 11:26:13 volumio systemd[1]: nmbd.service: Failed with result 'timeout'. Jan 27 11:26:13 volumio systemd[1]: Failed to start nmbd.service - Samba NMB Daemon. Jan 27 11:26:14 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon... Jan 27 11:26:14 volumio winbindd[2287]: [2026/01/27 11:26:14.311502, 0] ../../source3/winbindd/winbindd.c:1440(main) Jan 27 11:26:14 volumio winbindd[2287]: winbindd version 4.17.8-Raspbian started. Jan 27 11:26:14 volumio winbindd[2287]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jan 27 11:26:14 volumio winbindd[2287]: [2026/01/27 11:26:14.324877, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Jan 27 11:26:14 volumio winbindd[2287]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 27 11:26:14 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon. Jan 27 11:26:14 volumio volumio[2224]: info: Loading plugin "alarm-clock"... Jan 27 11:26:14 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... Jan 27 11:26:14 volumio volumio[2224]: info: Loading plugin "airplay_emulation"... Jan 27 11:26:14 volumio volumio[2224]: info: Starting Shairport Sync Jan 27 11:26:14 volumio volumio[2224]: info: Loading plugin "last_100"... Jan 27 11:26:14 volumio volumio[2224]: info: Loading plugin "webradio"... Jan 27 11:26:14 volumio volumio[2224]: info: Loading plugin "i2s_dacs"... Jan 27 11:26:14 volumio volumio[2224]: info: Loading plugin "volumiodiscovery"... Jan 27 11:26:14 volumio volumio[2224]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 27 11:26:14 volumio volumio[2224]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 27 11:26:14 volumio volumio[2224]: *** WARNING *** For more information see Jan 27 11:26:14 volumio volumio[2224]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 27 11:26:14 volumio volumio[2224]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 27 11:26:14 volumio volumio[2224]: *** WARNING *** For more information see Jan 27 11:26:14 volumio node[2224]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 27 11:26:14 volumio node[2224]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 27 11:26:14 volumio node[2224]: *** WARNING *** For more information see Jan 27 11:26:14 volumio node[2224]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 27 11:26:14 volumio node[2224]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 27 11:26:14 volumio node[2224]: *** WARNING *** For more information see Jan 27 11:26:14 volumio volumio[2224]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 27 11:26:14 volumio volumio[2224]: info: Discovery: Started advertising with name: Volumio Jan 27 11:26:14 volumio smbd[2304]: [2026/01/27 11:26:14.829765, 0] ../../source3/smbd/server.c:1741(main) Jan 27 11:26:14 volumio smbd[2304]: smbd version 4.17.8-Raspbian started. Jan 27 11:26:14 volumio smbd[2304]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jan 27 11:26:14 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 27 11:26:14 volumio volumio[2224]: info: Loading plugin "squeezelite_mc"... Jan 27 11:26:14 volumio sudo[2261]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:15 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Jan 27 11:26:15 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Jan 27 11:26:15 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Jan 27 11:26:15 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Jan 27 11:26:15 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jan 27 11:26:15 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Jan 27 11:26:15 volumio systemd[1]: Startup finished in 12.945s (kernel) + 1min 38.954s (userspace) = 1min 51.899s. Jan 27 11:26:15 volumio volumio[2224]: info: Loading plugin "outputs"... Jan 27 11:26:15 volumio volumio[2224]: info: Loading plugin "albumart"... Jan 27 11:26:16 volumio volumio[2224]: info: Plugin example_plugin is not enabled Jan 27 11:26:16 volumio volumio[2224]: info: Loading plugin "inputs"... Jan 27 11:26:16 volumio volumio[2224]: info: Loading plugin "updater_comm"... Jan 27 11:26:16 volumio volumio[2224]: info: Plugin mpdemulation is not enabled Jan 27 11:26:16 volumio volumio[2224]: info: Loading plugin "rest_api"... Jan 27 11:26:16 volumio volumio[2224]: info: Loading plugin "websocket"... Jan 27 11:26:16 volumio volumio[2224]: info: Starting Socket.io Server version 1.7.4 Jan 27 11:26:16 volumio volumio[2224]: info: Loading i18n strings for locale en Jan 27 11:26:16 volumio volumio[2224]: Updating browse sources language Jan 27 11:26:16 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 11:26:16 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:26:16 volumio volumio[2224]: info: CoreCommandRouter::initPlayerControls Jan 27 11:26:16 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:16 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:16 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:16 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:16 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:16 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:16 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:16 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:16 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:26:16 volumio volumio[2224]: Express server listening on port 3000 Jan 27 11:26:16 volumio volumio[2224]: [Metrics] WebUI: 6s 507.80ms Jan 27 11:26:16 volumio volumio[2224]: info: CoreStateMachine::resetVolumioState Jan 27 11:26:16 volumio volumio[2224]: info: CoreStateMachine::getcurrentVolume Jan 27 11:26:16 volumio volumio[2224]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 11:26:16 volumio volumio[2224]: info: Cannot read play queue from file Jan 27 11:26:16 volumio volumio[2224]: info: Volumio Network Manager: Network status updated: 0 Jan 27 11:26:16 volumio volumio-remote-updater[820]: [2026-01-27 11:26:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1769513173 101 Jan 27 11:26:16 volumio volumio[2224]: 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: 1 Jan 27 11:26:17 volumio volumio[2315]: Forking 3 albumart workers Jan 27 11:26:17 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 11:26:17 volumio volumio[2224]: info: VolumeController:: Volume=100 Mute =false Jan 27 11:26:17 volumio volumio[2224]: info: CoreStateMachine::pushState Jan 27 11:26:17 volumio volumio[2224]: info: CorePlayQueue::getTrack 0 Jan 27 11:26:17 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 11:26:17 volumio volumio[2224]: info: CoreCommandRouter::volumioPushState Jan 27 11:26:17 volumio volumio[2224]: info: CoreStateMachine::updateTrackBlock Jan 27 11:26:17 volumio volumio[2224]: info: CorePlayQueue::getTrackBlock Jan 27 11:26:17 volumio volumio[2224]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 11:26:17 volumio volumio[2224]: info: CoreStateMachine::setRepeat null single undefined Jan 27 11:26:17 volumio volumio[2224]: info: CoreStateMachine::pushState Jan 27 11:26:17 volumio volumio[2224]: info: CorePlayQueue::getTrack 0 Jan 27 11:26:17 volumio volumio[2224]: info: CoreCommandRouter::volumioPushState Jan 27 11:26:17 volumio volumio[2224]: info: CoreStateMachine::setRandom null Jan 27 11:26:17 volumio volumio[2224]: info: CoreStateMachine::pushState Jan 27 11:26:17 volumio volumio[2224]: info: CorePlayQueue::getTrack 0 Jan 27 11:26:17 volumio volumio[2224]: info: CoreCommandRouter::volumioPushState Jan 27 11:26:17 volumio volumio[2224]: info: Setting Device type: Raspberry PI Jan 27 11:26:17 volumio volumio[2224]: info: Completed loading Core Plugins Jan 27 11:26:17 volumio volumio[2224]: info: Preparing to generate the ALSA configuration file Jan 27 11:26:17 volumio volumio[2224]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 27 11:26:17 volumio volumio[2224]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Jan 27 11:26:17 volumio volumio[2224]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 27 11:26:17 volumio volumio[2224]: info: VolumeController:: Volume=100 Mute =false Jan 27 11:26:17 volumio volumio[2224]: info: CoreStateMachine::pushState Jan 27 11:26:17 volumio volumio[2224]: info: CorePlayQueue::getTrack 0 Jan 27 11:26:17 volumio volumio[2224]: info: CoreCommandRouter::volumioPushState Jan 27 11:26:17 volumio volumio[2224]: info: Discovery: adding 5fa55872-f14f-4905-9340-fa825c86bd20 Jan 27 11:26:17 volumio volumio[2224]: info: Discovery: Found device Volumio Jan 27 11:26:17 volumio volumio[2224]: info: CoreCommandRouter::volumioGetState Jan 27 11:26:17 volumio volumio[2224]: info: CorePlayQueue::getTrack 0 Jan 27 11:26:17 volumio volumio[2224]: info: Asound.conf file unchanged, so no further update is needed Jan 27 11:26:17 volumio volumio[2224]: info: Output device has changed, restarting MPD Jan 27 11:26:17 volumio volumio[2224]: info: Output device has changed, restarting Shairport Sync Jan 27 11:26:17 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:17 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 11:26:17 volumio sudo[2367]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 27 11:26:17 volumio sudo[2367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:17 volumio sudo[2369]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 27 11:26:17 volumio sudo[2369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:17 volumio sudo[2367]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:17 volumio volumio[2224]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 11:26:17 volumio volumio[2224]: info: ___________ START PLUGINS ___________ Jan 27 11:26:17 volumio volumio[2224]: info: ControllerMpd::onStart: Initializing MPD Jan 27 11:26:17 volumio volumio[2224]: info: Creating MPD Configuration file Jan 27 11:26:17 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:26:17 volumio volumio[2224]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 27 11:26:17 volumio volumio[2224]: info: [1769513177934] CoreMusicLibrary::Adding element Media Servers Jan 27 11:26:17 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 11:26:17 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 27 11:26:17 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:17 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 27 11:26:17 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 27 11:26:17 volumio systemd[1]: mpd.service: Consumed 1.473s CPU time. Jan 27 11:26:17 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 27 11:26:17 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 27 11:26:17 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 27 11:26:18 volumio sudo[2381]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 27 11:26:18 volumio sudo[2381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:18 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 11:26:18 volumio sudo[2379]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 27 11:26:18 volumio sudo[2379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:18 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 27 11:26:18 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 27 11:26:18 volumio sudo[2379]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:18 volumio volumio[2224]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 11:26:18 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:18 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 11:26:18 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 27 11:26:18 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 27 11:26:18 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 27 11:26:18 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 27 11:26:18 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 27 11:26:18 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 27 11:26:18 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 27 11:26:18 volumio sudo[2392]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 27 11:26:18 volumio sudo[2392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 27 11:26:18 volumio sudo[2392]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:18 volumio volumio[2224]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 11:26:18 volumio volumio[2224]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 27 11:26:18 volumio volumio[2224]: info: [1769513178393] CoreMusicLibrary::Adding element Last_100 Jan 27 11:26:18 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 11:26:18 volumio volumio[2224]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 27 11:26:18 volumio volumio[2224]: info: [1769513178439] CoreMusicLibrary::Adding element Webradio Jan 27 11:26:18 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 11:26:18 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 27 11:26:18 volumio volumio[2224]: info: Initializing BBC Radios Jan 27 11:26:18 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 27 11:26:18 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 11:26:19 volumio volumio[2224]: info: [squeezelite_mc] Starting proxy server... Jan 27 11:26:19 volumio volumio[2224]: info: Volumio Calling Home Jan 27 11:26:19 volumio volumio[2224]: info: [squeezelite_mc] Proxy server started on port 34057 Jan 27 11:26:19 volumio mpd[2397]: 2026-01-27T11:26:19 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 27 11:26:19 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 27 11:26:19 volumio sudo[2381]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:19 volumio sudo[2369]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:21 volumio volumio[2224]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 11:26:21 volumio volumio[2224]: Unhandled rejection Error: No sockets available, cannot start. Jan 27 11:26:21 volumio volumio[2224]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 27 11:26:21 volumio volumio[2224]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 27 11:26:21 volumio volumio[2224]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 27 11:26:21 volumio volumio[2224]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 27 11:26:21 volumio volumio[2224]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 27 11:26:21 volumio volumio[2224]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 27 11:26:21 volumio volumio[2224]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 27 11:26:21 volumio volumio[2224]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 27 11:26:21 volumio volumio[2224]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 27 11:26:21 volumio volumio[2224]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 27 11:26:21 volumio volumio[2224]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 27 11:26:21 volumio volumio[2224]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 27 11:26:21 volumio volumio[2224]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 27 11:26:21 volumio volumio[2224]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 27 11:26:21 volumio volumio[2224]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 27 11:26:21 volumio volumio[2224]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 27 11:26:22 volumio volumio[2224]: info: MPD Permissions set Jan 27 11:26:22 volumio volumio[2224]: info: MPD Permissions set Jan 27 11:26:22 volumio volumio[2224]: info: VolumeController:: Volume=100 Mute =false Jan 27 11:26:22 volumio volumio[2224]: info: CoreStateMachine::pushState Jan 27 11:26:22 volumio volumio[2224]: info: CorePlayQueue::getTrack 0 Jan 27 11:26:22 volumio volumio[2224]: info: CoreCommandRouter::volumioPushState Jan 27 11:26:22 volumio volumio[2224]: info: [squeezelite_mc] Server discovery started Jan 27 11:26:22 volumio volumio[2224]: info: [squeezelite_mc] Player finder started Jan 27 11:26:22 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:22 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:22 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:22 volumio volumio[2224]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:26:22 volumio volumio[2224]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 27 11:26:22 volumio volumio[2224]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 11:26:22 volumio volumio[2336]: Starting albumart workers Jan 27 11:26:22 volumio volumio[2224]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 27 11:26:22 volumio volumio[2224]: at doSend (node:dgram:717:16) Jan 27 11:26:22 volumio volumio[2224]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Jan 27 11:26:22 volumio volumio[2224]: at afterDns (node:dgram:663:5) Jan 27 11:26:22 volumio volumio[2224]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Jan 27 11:26:22 volumio volumio[2224]: errno: -101, Jan 27 11:26:22 volumio volumio[2224]: code: 'ENETUNREACH', Jan 27 11:26:22 volumio volumio[2224]: syscall: 'send', Jan 27 11:26:22 volumio volumio[2224]: address: '255.255.255.255', Jan 27 11:26:22 volumio volumio[2224]: port: 3483 Jan 27 11:26:22 volumio volumio[2224]: } Jan 27 11:26:22 volumio volumio[2224]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 11:26:22 volumio volumio[2338]: Starting albumart workers Jan 27 11:26:23 volumio volumio[2339]: Starting albumart workers Jan 27 11:26:26 volumio sudo[2462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-27 11:25' Jan 27 11:26:26 volumio sudo[2462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:27 volumio sudo[2462]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:27 volumio volumio-remote-updater[820]: [2026-01-27 11:26:27] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 27 11:26:27 volumio volumio-remote-updater[820]: [2026-01-27 11:26:27] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 27 11:26:27 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 27 11:26:27 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 27 11:26:27 volumio systemd[1]: volumio.service: Consumed 36.325s CPU time. Jan 27 11:26:28 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 27 11:26:28 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Jan 27 11:26:28 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Jan 27 11:26:28 volumio systemd[1]: volumio.service: Consumed 36.325s CPU time. Jan 27 11:26:28 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Jan 27 11:26:28 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 27 11:26:30 volumio volumio[2500]: info: ------------------------------------------- Jan 27 11:26:30 volumio volumio[2500]: info: ----- Volumio3 ---- Jan 27 11:26:30 volumio volumio[2500]: info: ------------------------------------------- Jan 27 11:26:30 volumio volumio[2500]: info: ----- System startup ---- Jan 27 11:26:30 volumio volumio[2500]: info: ------------------------------------------- Jan 27 11:26:30 volumio volumio[2500]: info: MYVOLUMIO Environment detected Jan 27 11:26:30 volumio volumio[2500]: info: Plugin folders cleanup Jan 27 11:26:30 volumio volumio[2500]: info: Scanning into folder /volumio/app/plugins/ Jan 27 11:26:30 volumio volumio[2500]: info: Scanning category audio_interface Jan 27 11:26:30 volumio volumio[2500]: info: Scanning category miscellanea Jan 27 11:26:30 volumio volumio[2500]: info: Scanning category music_service Jan 27 11:26:30 volumio volumio[2500]: info: Scanning category plugins.json Jan 27 11:26:30 volumio volumio[2500]: info: Scanning category system_controller Jan 27 11:26:30 volumio volumio[2500]: info: Scanning category user_interface Jan 27 11:26:30 volumio volumio[2500]: info: Scanning into folder /data/plugins/ Jan 27 11:26:30 volumio volumio[2500]: info: Scanning category music_service Jan 27 11:26:30 volumio volumio[2500]: info: Plugin folders cleanup completed Jan 27 11:26:30 volumio volumio[2500]: info: ------------------------------------------- Jan 27 11:26:30 volumio volumio[2500]: info: ----- Core plugins startup ---- Jan 27 11:26:30 volumio volumio[2500]: info: ------------------------------------------- Jan 27 11:26:30 volumio volumio[2500]: info: Loading plugins from folder /volumio/app/plugins/ Jan 27 11:26:30 volumio volumio[2500]: info: Adding plugin upnp to MyMusic Plugins Jan 27 11:26:30 volumio volumio[2500]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 27 11:26:30 volumio volumio[2500]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 27 11:26:30 volumio volumio[2500]: info: Loading plugins from folder /data/plugins/ Jan 27 11:26:30 volumio volumio[2500]: info: Loading plugin "system"... Jan 27 11:26:30 volumio volumio[2500]: info: Loading plugin "appearance"... Jan 27 11:26:31 volumio volumio[2500]: info: Loading plugin "network"... Jan 27 11:26:31 volumio volumio[2500]: info: Refreshing Cached IP Addresses Jan 27 11:26:31 volumio sudo[2524]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 27 11:26:31 volumio sudo[2524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:31 volumio sudo[2525]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 27 11:26:31 volumio sudo[2525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:31 volumio sudo[2524]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:31 volumio sudo[2525]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:31 volumio sudo[2533]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 27 11:26:31 volumio sudo[2533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:31 volumio volumio[2500]: info: Loading plugin "services"... Jan 27 11:26:31 volumio volumio[2500]: info: Loading plugin "alsa_controller"... Jan 27 11:26:32 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 27 11:26:32 volumio volumio[2500]: info: Loading plugin "wizard"... Jan 27 11:26:32 volumio volumio[2500]: info: Loading plugin "networkfs"... Jan 27 11:26:32 volumio volumio[2500]: info: Starting Udev Watcher for removable devices Jan 27 11:26:32 volumio volumio[2500]: info: Ignoring mount for partition: boot Jan 27 11:26:32 volumio volumio[2500]: info: Ignoring mount for partition: volumio Jan 27 11:26:32 volumio volumio[2500]: info: Ignoring mount for partition: volumio_data Jan 27 11:26:32 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 27 11:26:32 volumio volumio[2500]: info: Loading plugin "volumio_command_line_client"... Jan 27 11:26:32 volumio volumio[2500]: info: Loading plugin "upnp"... Jan 27 11:26:32 volumio volumio[2500]: info: [1769513192098] Starting Upmpd Daemon Jan 27 11:26:32 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 27 11:26:32 volumio volumio[2500]: info: Loading plugin "my_music"... Jan 27 11:26:32 volumio volumio[2500]: info: Loading plugin "mpd"... Jan 27 11:26:32 volumio volumio[2500]: info: Loading plugin "upnp_browser"... Jan 27 11:26:32 volumio volumio-remote-updater[820]: [2026-01-27 11:26:32] [connect] Successful connection Jan 27 11:26:33 volumio volumio[2500]: info: Loading plugin "alarm-clock"... Jan 27 11:26:33 volumio volumio[2500]: info: Loading plugin "airplay_emulation"... Jan 27 11:26:33 volumio volumio[2500]: info: Starting Shairport Sync Jan 27 11:26:33 volumio volumio[2500]: info: Loading plugin "last_100"... Jan 27 11:26:33 volumio volumio[2500]: info: Loading plugin "webradio"... Jan 27 11:26:33 volumio volumio[2500]: info: Loading plugin "i2s_dacs"... Jan 27 11:26:33 volumio volumio[2500]: info: Loading plugin "volumiodiscovery"... Jan 27 11:26:33 volumio volumio[2500]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 27 11:26:33 volumio volumio[2500]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 27 11:26:33 volumio volumio[2500]: *** WARNING *** For more information see Jan 27 11:26:33 volumio volumio[2500]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 27 11:26:33 volumio volumio[2500]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 27 11:26:33 volumio node[2500]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 27 11:26:33 volumio volumio[2500]: *** WARNING *** For more information see Jan 27 11:26:33 volumio node[2500]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 27 11:26:33 volumio node[2500]: *** WARNING *** For more information see Jan 27 11:26:33 volumio node[2500]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 27 11:26:33 volumio node[2500]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 27 11:26:33 volumio node[2500]: *** WARNING *** For more information see Jan 27 11:26:33 volumio volumio[2500]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 27 11:26:33 volumio volumio[2500]: info: Discovery: Started advertising with name: Volumio Jan 27 11:26:33 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 27 11:26:33 volumio volumio[2500]: info: Loading plugin "squeezelite_mc"... Jan 27 11:26:34 volumio volumio[2500]: info: Loading plugin "outputs"... Jan 27 11:26:34 volumio volumio[2500]: info: Loading plugin "albumart"... Jan 27 11:26:34 volumio volumio[2500]: info: Plugin example_plugin is not enabled Jan 27 11:26:34 volumio volumio[2500]: info: Loading plugin "inputs"... Jan 27 11:26:34 volumio volumio[2500]: info: Loading plugin "updater_comm"... Jan 27 11:26:34 volumio sudo[2533]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:34 volumio volumio[2500]: info: Plugin mpdemulation is not enabled Jan 27 11:26:34 volumio volumio[2500]: info: Loading plugin "rest_api"... Jan 27 11:26:34 volumio volumio[2500]: info: Loading plugin "websocket"... Jan 27 11:26:34 volumio volumio[2500]: info: Starting Socket.io Server version 1.7.4 Jan 27 11:26:34 volumio volumio[2500]: info: Loading i18n strings for locale en Jan 27 11:26:34 volumio volumio[2500]: Updating browse sources language Jan 27 11:26:34 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 11:26:35 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:26:35 volumio volumio[2500]: info: CoreCommandRouter::initPlayerControls Jan 27 11:26:35 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:35 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:35 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:35 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:35 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:35 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:35 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:35 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:35 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:26:35 volumio volumio[2500]: Express server listening on port 3000 Jan 27 11:26:35 volumio volumio[2500]: [Metrics] WebUI: 5s 608.27ms Jan 27 11:26:35 volumio volumio[2500]: info: CoreStateMachine::resetVolumioState Jan 27 11:26:35 volumio volumio[2500]: info: CoreStateMachine::getcurrentVolume Jan 27 11:26:35 volumio volumio[2500]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 11:26:35 volumio volumio[2500]: info: Cannot read play queue from file Jan 27 11:26:35 volumio volumio[2500]: info: Volumio Network Manager: Network status updated: 0 Jan 27 11:26:35 volumio volumio[2558]: Forking 3 albumart workers Jan 27 11:26:35 volumio volumio-remote-updater[820]: [2026-01-27 11:26:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1769513192 101 Jan 27 11:26:35 volumio volumio[2500]: 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: 1 Jan 27 11:26:35 volumio volumio[2500]: info: VolumeController:: Volume=100 Mute =false Jan 27 11:26:35 volumio volumio[2500]: info: CoreStateMachine::pushState Jan 27 11:26:35 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Jan 27 11:26:35 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 11:26:35 volumio volumio[2500]: info: CoreCommandRouter::volumioPushState Jan 27 11:26:35 volumio volumio[2500]: info: CoreStateMachine::updateTrackBlock Jan 27 11:26:35 volumio volumio[2500]: info: CorePlayQueue::getTrackBlock Jan 27 11:26:35 volumio volumio[2500]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 11:26:35 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 11:26:35 volumio volumio[2500]: info: VolumeController:: Volume=100 Mute =false Jan 27 11:26:35 volumio volumio[2500]: info: CoreStateMachine::pushState Jan 27 11:26:35 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Jan 27 11:26:35 volumio volumio[2500]: info: CoreCommandRouter::volumioPushState Jan 27 11:26:35 volumio volumio[2500]: info: CoreStateMachine::setRepeat null single undefined Jan 27 11:26:35 volumio volumio[2500]: info: CoreStateMachine::pushState Jan 27 11:26:35 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Jan 27 11:26:35 volumio volumio[2500]: info: CoreCommandRouter::volumioPushState Jan 27 11:26:35 volumio volumio[2500]: info: CoreStateMachine::setRandom null Jan 27 11:26:35 volumio volumio[2500]: info: CoreStateMachine::pushState Jan 27 11:26:35 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Jan 27 11:26:35 volumio volumio[2500]: info: CoreCommandRouter::volumioPushState Jan 27 11:26:35 volumio volumio[2500]: info: Setting Device type: Raspberry PI Jan 27 11:26:35 volumio volumio[2500]: info: Completed loading Core Plugins Jan 27 11:26:35 volumio volumio[2500]: info: Preparing to generate the ALSA configuration file Jan 27 11:26:35 volumio volumio[2500]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 27 11:26:35 volumio volumio[2500]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Jan 27 11:26:35 volumio volumio[2500]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 27 11:26:36 volumio volumio[2500]: info: Discovery: adding 5fa55872-f14f-4905-9340-fa825c86bd20 Jan 27 11:26:36 volumio volumio[2500]: info: Discovery: Found device Volumio Jan 27 11:26:36 volumio volumio[2500]: info: CoreCommandRouter::volumioGetState Jan 27 11:26:36 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Jan 27 11:26:36 volumio volumio[2500]: info: Asound.conf file unchanged, so no further update is needed Jan 27 11:26:36 volumio volumio[2500]: info: Output device has changed, restarting MPD Jan 27 11:26:36 volumio volumio[2500]: info: Output device has changed, restarting Shairport Sync Jan 27 11:26:36 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:36 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 11:26:36 volumio sudo[2613]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 27 11:26:36 volumio sudo[2613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:36 volumio sudo[2611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 27 11:26:36 volumio sudo[2611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:36 volumio sudo[2611]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:36 volumio volumio[2500]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 11:26:36 volumio volumio[2500]: info: ___________ START PLUGINS ___________ Jan 27 11:26:36 volumio volumio[2500]: info: ControllerMpd::onStart: Initializing MPD Jan 27 11:26:36 volumio volumio[2500]: info: Creating MPD Configuration file Jan 27 11:26:36 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 27 11:26:36 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 27 11:26:36 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 27 11:26:36 volumio systemd[1]: mpd.service: Consumed 1.490s CPU time. Jan 27 11:26:36 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 27 11:26:36 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 27 11:26:36 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 27 11:26:36 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:26:36 volumio volumio[2500]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 27 11:26:36 volumio volumio[2500]: info: [1769513196530] CoreMusicLibrary::Adding element Media Servers Jan 27 11:26:36 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 11:26:36 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 27 11:26:36 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 27 11:26:36 volumio sudo[2624]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 27 11:26:36 volumio sudo[2624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:36 volumio sudo[2622]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 27 11:26:36 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:36 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 11:26:36 volumio sudo[2625]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 27 11:26:36 volumio sudo[2625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 27 11:26:36 volumio sudo[2625]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:36 volumio sudo[2622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:36 volumio sudo[2622]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:36 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 27 11:26:36 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 27 11:26:36 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 27 11:26:36 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 27 11:26:36 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 27 11:26:36 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 27 11:26:36 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 27 11:26:36 volumio sudo[2635]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 27 11:26:36 volumio sudo[2635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 27 11:26:36 volumio sudo[2635]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:36 volumio volumio[2500]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 11:26:36 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:36 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 11:26:37 volumio volumio[2500]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 11:26:37 volumio volumio[2500]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 27 11:26:37 volumio volumio[2500]: info: [1769513197107] CoreMusicLibrary::Adding element Last_100 Jan 27 11:26:37 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 11:26:37 volumio volumio[2500]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 27 11:26:37 volumio volumio[2500]: info: [1769513197137] CoreMusicLibrary::Adding element Webradio Jan 27 11:26:37 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 11:26:37 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 27 11:26:37 volumio volumio[2500]: info: Initializing BBC Radios Jan 27 11:26:37 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 27 11:26:37 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 11:26:37 volumio volumio[2500]: info: [squeezelite_mc] Starting proxy server... Jan 27 11:26:37 volumio volumio[2500]: info: Volumio Calling Home Jan 27 11:26:38 volumio volumio[2500]: info: [squeezelite_mc] Proxy server started on port 35041 Jan 27 11:26:38 volumio mpd[2637]: 2026-01-27T11:26:38 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 27 11:26:38 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 27 11:26:38 volumio sudo[2624]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:38 volumio sudo[2613]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:40 volumio volumio[2500]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 11:26:40 volumio volumio[2500]: Unhandled rejection Error: No sockets available, cannot start. Jan 27 11:26:40 volumio volumio[2500]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 27 11:26:40 volumio volumio[2500]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 27 11:26:40 volumio volumio[2500]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 27 11:26:40 volumio volumio[2500]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 27 11:26:40 volumio volumio[2500]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 27 11:26:40 volumio volumio[2500]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 27 11:26:40 volumio volumio[2500]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 27 11:26:40 volumio volumio[2500]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 27 11:26:40 volumio volumio[2500]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 27 11:26:40 volumio volumio[2500]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 27 11:26:40 volumio volumio[2500]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 27 11:26:40 volumio volumio[2500]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 27 11:26:40 volumio volumio[2500]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 27 11:26:40 volumio volumio[2500]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 27 11:26:40 volumio volumio[2500]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 27 11:26:40 volumio volumio[2500]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 27 11:26:40 volumio volumio[2573]: Starting albumart workers Jan 27 11:26:40 volumio volumio[2500]: info: MPD Permissions set Jan 27 11:26:40 volumio volumio[2500]: info: MPD Permissions set Jan 27 11:26:40 volumio volumio[2500]: info: VolumeController:: Volume=100 Mute =false Jan 27 11:26:40 volumio volumio[2500]: info: CoreStateMachine::pushState Jan 27 11:26:40 volumio volumio[2500]: info: CorePlayQueue::getTrack 0 Jan 27 11:26:40 volumio volumio[2500]: info: CoreCommandRouter::volumioPushState Jan 27 11:26:40 volumio volumio[2500]: info: [squeezelite_mc] Server discovery started Jan 27 11:26:40 volumio volumio[2500]: info: [squeezelite_mc] Player finder started Jan 27 11:26:40 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:40 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:40 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:40 volumio volumio[2500]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:26:40 volumio volumio[2500]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 27 11:26:40 volumio volumio[2500]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 11:26:40 volumio volumio[2500]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 27 11:26:40 volumio volumio[2500]: at doSend (node:dgram:717:16) Jan 27 11:26:40 volumio volumio[2500]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Jan 27 11:26:40 volumio volumio[2500]: at afterDns (node:dgram:663:5) Jan 27 11:26:40 volumio volumio[2500]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Jan 27 11:26:40 volumio volumio[2500]: errno: -101, Jan 27 11:26:40 volumio volumio[2500]: code: 'ENETUNREACH', Jan 27 11:26:40 volumio volumio[2500]: syscall: 'send', Jan 27 11:26:40 volumio volumio[2500]: address: '255.255.255.255', Jan 27 11:26:40 volumio volumio[2500]: port: 3483 Jan 27 11:26:40 volumio volumio[2500]: } Jan 27 11:26:40 volumio volumio[2500]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 11:26:41 volumio volumio[2574]: Starting albumart workers Jan 27 11:26:41 volumio volumio[2575]: Starting albumart workers Jan 27 11:26:45 volumio sudo[2713]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-27 11:25' Jan 27 11:26:45 volumio sudo[2713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:46 volumio sudo[2713]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:46 volumio volumio-remote-updater[820]: [2026-01-27 11:26:46] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 27 11:26:46 volumio volumio-remote-updater[820]: [2026-01-27 11:26:46] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 27 11:26:46 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 27 11:26:46 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 27 11:26:46 volumio systemd[1]: volumio.service: Consumed 35.875s CPU time. Jan 27 11:26:46 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 27 11:26:46 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 27 11:26:47 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Jan 27 11:26:47 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 27 11:26:47 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Jan 27 11:26:47 volumio systemd[1]: volumio.service: Consumed 35.875s CPU time. Jan 27 11:26:47 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Jan 27 11:26:47 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 27 11:26:49 volumio volumio[2728]: info: ------------------------------------------- Jan 27 11:26:49 volumio volumio[2728]: info: ----- Volumio3 ---- Jan 27 11:26:49 volumio volumio[2728]: info: ------------------------------------------- Jan 27 11:26:49 volumio volumio[2728]: info: ----- System startup ---- Jan 27 11:26:49 volumio volumio[2728]: info: ------------------------------------------- Jan 27 11:26:50 volumio volumio[2728]: info: MYVOLUMIO Environment detected Jan 27 11:26:50 volumio volumio[2728]: info: Plugin folders cleanup Jan 27 11:26:50 volumio volumio[2728]: info: Scanning into folder /volumio/app/plugins/ Jan 27 11:26:50 volumio volumio[2728]: info: Scanning category audio_interface Jan 27 11:26:50 volumio volumio[2728]: info: Scanning category miscellanea Jan 27 11:26:50 volumio volumio[2728]: info: Scanning category music_service Jan 27 11:26:50 volumio volumio[2728]: info: Scanning category plugins.json Jan 27 11:26:50 volumio volumio[2728]: info: Scanning category system_controller Jan 27 11:26:50 volumio volumio[2728]: info: Scanning category user_interface Jan 27 11:26:50 volumio volumio[2728]: info: Scanning into folder /data/plugins/ Jan 27 11:26:50 volumio volumio[2728]: info: Scanning category music_service Jan 27 11:26:50 volumio volumio[2728]: info: Plugin folders cleanup completed Jan 27 11:26:50 volumio volumio[2728]: info: ------------------------------------------- Jan 27 11:26:50 volumio volumio[2728]: info: ----- Core plugins startup ---- Jan 27 11:26:50 volumio volumio[2728]: info: ------------------------------------------- Jan 27 11:26:50 volumio volumio[2728]: info: Loading plugins from folder /volumio/app/plugins/ Jan 27 11:26:50 volumio volumio[2728]: info: Adding plugin upnp to MyMusic Plugins Jan 27 11:26:50 volumio volumio[2728]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 27 11:26:50 volumio volumio[2728]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 27 11:26:50 volumio volumio[2728]: info: Loading plugins from folder /data/plugins/ Jan 27 11:26:50 volumio volumio[2728]: info: Loading plugin "system"... Jan 27 11:26:50 volumio volumio[2728]: info: Loading plugin "appearance"... Jan 27 11:26:50 volumio volumio[2728]: info: Loading plugin "network"... Jan 27 11:26:50 volumio volumio[2728]: info: Refreshing Cached IP Addresses Jan 27 11:26:50 volumio sudo[2756]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 27 11:26:50 volumio sudo[2758]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 27 11:26:50 volumio sudo[2758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:50 volumio volumio[2728]: info: Loading plugin "services"... Jan 27 11:26:50 volumio sudo[2756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:50 volumio volumio[2728]: info: Loading plugin "alsa_controller"... Jan 27 11:26:50 volumio sudo[2758]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:51 volumio sudo[2756]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:51 volumio sudo[2770]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 27 11:26:51 volumio sudo[2770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:51 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 27 11:26:51 volumio volumio[2728]: info: Loading plugin "wizard"... Jan 27 11:26:51 volumio volumio[2728]: info: Loading plugin "networkfs"... Jan 27 11:26:51 volumio volumio[2728]: info: Starting Udev Watcher for removable devices Jan 27 11:26:51 volumio volumio[2728]: info: Ignoring mount for partition: boot Jan 27 11:26:51 volumio volumio[2728]: info: Ignoring mount for partition: volumio Jan 27 11:26:51 volumio volumio[2728]: info: Ignoring mount for partition: volumio_data Jan 27 11:26:51 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 27 11:26:51 volumio volumio[2728]: info: Loading plugin "volumio_command_line_client"... Jan 27 11:26:51 volumio volumio[2728]: info: Loading plugin "upnp"... Jan 27 11:26:51 volumio volumio[2728]: info: [1769513211190] Starting Upmpd Daemon Jan 27 11:26:51 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 27 11:26:51 volumio volumio[2728]: info: Loading plugin "my_music"... Jan 27 11:26:51 volumio volumio[2728]: info: Loading plugin "mpd"... Jan 27 11:26:51 volumio volumio[2728]: info: Loading plugin "upnp_browser"... Jan 27 11:26:51 volumio volumio-remote-updater[820]: [2026-01-27 11:26:51] [connect] Successful connection Jan 27 11:26:52 volumio volumio[2728]: info: Loading plugin "alarm-clock"... Jan 27 11:26:52 volumio volumio[2728]: info: Loading plugin "airplay_emulation"... Jan 27 11:26:52 volumio volumio[2728]: info: Starting Shairport Sync Jan 27 11:26:52 volumio volumio[2728]: info: Loading plugin "last_100"... Jan 27 11:26:52 volumio volumio[2728]: info: Loading plugin "webradio"... Jan 27 11:26:52 volumio volumio[2728]: info: Loading plugin "i2s_dacs"... Jan 27 11:26:52 volumio volumio[2728]: info: Loading plugin "volumiodiscovery"... Jan 27 11:26:52 volumio volumio[2728]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 27 11:26:52 volumio node[2728]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 27 11:26:52 volumio volumio[2728]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 27 11:26:52 volumio node[2728]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 27 11:26:52 volumio volumio[2728]: *** WARNING *** For more information see Jan 27 11:26:52 volumio node[2728]: *** WARNING *** For more information see Jan 27 11:26:52 volumio volumio[2728]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 27 11:26:52 volumio node[2728]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 27 11:26:52 volumio volumio[2728]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 27 11:26:52 volumio node[2728]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 27 11:26:52 volumio volumio[2728]: *** WARNING *** For more information see Jan 27 11:26:52 volumio node[2728]: *** WARNING *** For more information see Jan 27 11:26:52 volumio volumio[2728]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 27 11:26:52 volumio volumio[2728]: info: Discovery: Started advertising with name: Volumio Jan 27 11:26:52 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 27 11:26:52 volumio volumio[2728]: info: Loading plugin "squeezelite_mc"... Jan 27 11:26:53 volumio sudo[2770]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:53 volumio volumio[2728]: info: Loading plugin "outputs"... Jan 27 11:26:53 volumio volumio[2728]: info: Loading plugin "albumart"... Jan 27 11:26:53 volumio volumio[2728]: info: Plugin example_plugin is not enabled Jan 27 11:26:53 volumio volumio[2728]: info: Loading plugin "inputs"... Jan 27 11:26:53 volumio volumio[2728]: info: Loading plugin "updater_comm"... Jan 27 11:26:53 volumio volumio[2728]: info: Plugin mpdemulation is not enabled Jan 27 11:26:53 volumio volumio[2728]: info: Loading plugin "rest_api"... Jan 27 11:26:53 volumio volumio[2728]: info: Loading plugin "websocket"... Jan 27 11:26:53 volumio volumio[2728]: info: Starting Socket.io Server version 1.7.4 Jan 27 11:26:53 volumio volumio[2728]: info: Loading i18n strings for locale en Jan 27 11:26:53 volumio volumio[2728]: Updating browse sources language Jan 27 11:26:53 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 11:26:54 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:26:54 volumio volumio[2728]: info: CoreCommandRouter::initPlayerControls Jan 27 11:26:54 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:54 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:54 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:54 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:54 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:54 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:54 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:54 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:54 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:26:54 volumio volumio[2728]: Express server listening on port 3000 Jan 27 11:26:54 volumio volumio[2728]: [Metrics] WebUI: 5s 648.12ms Jan 27 11:26:54 volumio volumio[2728]: info: CoreStateMachine::resetVolumioState Jan 27 11:26:54 volumio volumio[2728]: info: CoreStateMachine::getcurrentVolume Jan 27 11:26:54 volumio volumio[2728]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 11:26:54 volumio volumio[2728]: info: Cannot read play queue from file Jan 27 11:26:54 volumio volumio[2728]: info: Volumio Network Manager: Network status updated: 0 Jan 27 11:26:54 volumio volumio[2791]: Forking 3 albumart workers Jan 27 11:26:54 volumio volumio-remote-updater[820]: [2026-01-27 11:26:54] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1769513211 101 Jan 27 11:26:54 volumio volumio[2728]: 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: 1 Jan 27 11:26:54 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 11:26:55 volumio volumio[2728]: info: VolumeController:: Volume=100 Mute =false Jan 27 11:26:55 volumio volumio[2728]: info: CoreStateMachine::pushState Jan 27 11:26:55 volumio volumio[2728]: info: CorePlayQueue::getTrack 0 Jan 27 11:26:55 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 11:26:55 volumio volumio[2728]: info: CoreCommandRouter::volumioPushState Jan 27 11:26:55 volumio volumio[2728]: info: CoreStateMachine::updateTrackBlock Jan 27 11:26:55 volumio volumio[2728]: info: CorePlayQueue::getTrackBlock Jan 27 11:26:55 volumio volumio[2728]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 11:26:55 volumio volumio[2728]: info: CoreStateMachine::setRepeat null single undefined Jan 27 11:26:55 volumio volumio[2728]: info: CoreStateMachine::pushState Jan 27 11:26:55 volumio volumio[2728]: info: CorePlayQueue::getTrack 0 Jan 27 11:26:55 volumio volumio[2728]: info: CoreCommandRouter::volumioPushState Jan 27 11:26:55 volumio volumio[2728]: info: CoreStateMachine::setRandom null Jan 27 11:26:55 volumio volumio[2728]: info: CoreStateMachine::pushState Jan 27 11:26:55 volumio volumio[2728]: info: CorePlayQueue::getTrack 0 Jan 27 11:26:55 volumio volumio[2728]: info: CoreCommandRouter::volumioPushState Jan 27 11:26:55 volumio volumio[2728]: info: Setting Device type: Raspberry PI Jan 27 11:26:55 volumio volumio[2728]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 27 11:26:55 volumio volumio[2728]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Jan 27 11:26:55 volumio volumio[2728]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 27 11:26:55 volumio volumio[2728]: info: Completed loading Core Plugins Jan 27 11:26:55 volumio volumio[2728]: info: Preparing to generate the ALSA configuration file Jan 27 11:26:55 volumio volumio[2728]: info: Discovery: adding 5fa55872-f14f-4905-9340-fa825c86bd20 Jan 27 11:26:55 volumio volumio[2728]: info: Discovery: Found device Volumio Jan 27 11:26:55 volumio volumio[2728]: info: CoreCommandRouter::volumioGetState Jan 27 11:26:55 volumio volumio[2728]: info: CorePlayQueue::getTrack 0 Jan 27 11:26:55 volumio volumio[2728]: info: Asound.conf file unchanged, so no further update is needed Jan 27 11:26:55 volumio volumio[2728]: info: Output device has changed, restarting MPD Jan 27 11:26:55 volumio volumio[2728]: info: Output device has changed, restarting Shairport Sync Jan 27 11:26:55 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:55 volumio sudo[2845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 27 11:26:55 volumio sudo[2845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:55 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 11:26:55 volumio sudo[2845]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:55 volumio volumio[2728]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 11:26:55 volumio sudo[2850]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 27 11:26:55 volumio volumio[2728]: info: ___________ START PLUGINS ___________ Jan 27 11:26:55 volumio volumio[2728]: info: ControllerMpd::onStart: Initializing MPD Jan 27 11:26:55 volumio sudo[2850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:55 volumio volumio[2728]: info: Creating MPD Configuration file Jan 27 11:26:55 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:26:55 volumio volumio[2728]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 27 11:26:55 volumio volumio[2728]: info: [1769513215996] CoreMusicLibrary::Adding element Media Servers Jan 27 11:26:55 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 11:26:56 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 27 11:26:56 volumio sudo[2855]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 27 11:26:56 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 27 11:26:56 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 27 11:26:56 volumio systemd[1]: mpd.service: Consumed 1.499s CPU time. Jan 27 11:26:56 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 27 11:26:56 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 27 11:26:56 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 27 11:26:56 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:56 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 11:26:56 volumio sudo[2857]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 27 11:26:56 volumio sudo[2855]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:56 volumio sudo[2857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:26:56 volumio sudo[2855]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:56 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 27 11:26:56 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 27 11:26:56 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 27 11:26:56 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 27 11:26:56 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 27 11:26:56 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 27 11:26:56 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 27 11:26:56 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 27 11:26:56 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 27 11:26:56 volumio volumio[2728]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 11:26:56 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:26:56 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 11:26:56 volumio sudo[2867]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 27 11:26:56 volumio sudo[2867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 27 11:26:56 volumio sudo[2867]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:56 volumio volumio[2728]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 11:26:56 volumio volumio[2728]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 27 11:26:56 volumio volumio[2728]: info: [1769513216421] CoreMusicLibrary::Adding element Last_100 Jan 27 11:26:56 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 11:26:56 volumio volumio[2728]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 27 11:26:56 volumio volumio[2728]: info: [1769513216463] CoreMusicLibrary::Adding element Webradio Jan 27 11:26:56 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 11:26:56 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 27 11:26:56 volumio volumio[2728]: info: Initializing BBC Radios Jan 27 11:26:57 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 27 11:26:57 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 11:26:57 volumio volumio[2728]: info: [squeezelite_mc] Starting proxy server... Jan 27 11:26:57 volumio volumio[2728]: info: Volumio Calling Home Jan 27 11:26:57 volumio mpd[2872]: 2026-01-27T11:26:57 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 27 11:26:57 volumio volumio[2728]: info: [squeezelite_mc] Proxy server started on port 45839 Jan 27 11:26:57 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 27 11:26:57 volumio sudo[2850]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:57 volumio sudo[2857]: pam_unix(sudo:session): session closed for user root Jan 27 11:26:59 volumio volumio[2728]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 11:26:59 volumio volumio[2728]: info: VolumeController:: Volume=100 Mute =false Jan 27 11:26:59 volumio volumio[2728]: info: CoreStateMachine::pushState Jan 27 11:26:59 volumio volumio[2728]: info: CorePlayQueue::getTrack 0 Jan 27 11:26:59 volumio volumio[2728]: info: CoreCommandRouter::volumioPushState Jan 27 11:26:59 volumio volumio[2728]: Unhandled rejection Error: No sockets available, cannot start. Jan 27 11:26:59 volumio volumio[2728]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 27 11:26:59 volumio volumio[2728]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 27 11:26:59 volumio volumio[2728]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 27 11:26:59 volumio volumio[2728]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 27 11:26:59 volumio volumio[2728]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 27 11:26:59 volumio volumio[2728]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 27 11:26:59 volumio volumio[2728]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 27 11:26:59 volumio volumio[2728]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 27 11:26:59 volumio volumio[2728]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 27 11:26:59 volumio volumio[2728]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 27 11:26:59 volumio volumio[2728]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 27 11:26:59 volumio volumio[2728]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 27 11:26:59 volumio volumio[2728]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 27 11:26:59 volumio volumio[2728]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 27 11:26:59 volumio volumio[2728]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 27 11:26:59 volumio volumio[2728]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 27 11:26:59 volumio volumio[2808]: Starting albumart workers Jan 27 11:27:00 volumio volumio[2728]: info: MPD Permissions set Jan 27 11:27:00 volumio volumio[2728]: info: MPD Permissions set Jan 27 11:27:00 volumio volumio[2728]: info: VolumeController:: Volume=100 Mute =false Jan 27 11:27:00 volumio volumio[2728]: info: CoreStateMachine::pushState Jan 27 11:27:00 volumio volumio[2728]: info: CorePlayQueue::getTrack 0 Jan 27 11:27:00 volumio volumio[2728]: info: CoreCommandRouter::volumioPushState Jan 27 11:27:00 volumio volumio[2728]: info: [squeezelite_mc] Server discovery started Jan 27 11:27:00 volumio volumio[2728]: info: [squeezelite_mc] Player finder started Jan 27 11:27:00 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:27:00 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:27:00 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:27:00 volumio volumio[2728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:27:00 volumio volumio[2728]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 27 11:27:00 volumio volumio[2728]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 11:27:00 volumio volumio[2728]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 27 11:27:00 volumio volumio[2728]: at doSend (node:dgram:717:16) Jan 27 11:27:00 volumio volumio[2728]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Jan 27 11:27:00 volumio volumio[2728]: at afterDns (node:dgram:663:5) Jan 27 11:27:00 volumio volumio[2728]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Jan 27 11:27:00 volumio volumio[2728]: errno: -101, Jan 27 11:27:00 volumio volumio[2728]: code: 'ENETUNREACH', Jan 27 11:27:00 volumio volumio[2728]: syscall: 'send', Jan 27 11:27:00 volumio volumio[2728]: address: '255.255.255.255', Jan 27 11:27:00 volumio volumio[2728]: port: 3483 Jan 27 11:27:00 volumio volumio[2728]: } Jan 27 11:27:00 volumio volumio[2728]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 11:27:00 volumio volumio[2806]: Starting albumart workers Jan 27 11:27:01 volumio volumio[2807]: Starting albumart workers Jan 27 11:27:04 volumio sudo[2944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-27 11:26' Jan 27 11:27:04 volumio sudo[2944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:27:05 volumio sudo[2944]: pam_unix(sudo:session): session closed for user root Jan 27 11:27:06 volumio volumio-remote-updater[820]: [2026-01-27 11:27:06] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 27 11:27:06 volumio volumio-remote-updater[820]: [2026-01-27 11:27:06] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 27 11:27:06 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 27 11:27:06 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 27 11:27:06 volumio systemd[1]: volumio.service: Consumed 37.079s CPU time. Jan 27 11:27:06 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 27 11:27:06 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Jan 27 11:27:06 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Jan 27 11:27:06 volumio systemd[1]: volumio.service: Consumed 37.079s CPU time. Jan 27 11:27:06 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Jan 27 11:27:06 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 27 11:27:07 volumio volumio[2961]: info: ------------------------------------------- Jan 27 11:27:07 volumio volumio[2961]: info: ----- Volumio3 ---- Jan 27 11:27:07 volumio volumio[2961]: info: ------------------------------------------- Jan 27 11:27:07 volumio volumio[2961]: info: ----- System startup ---- Jan 27 11:27:07 volumio volumio[2961]: info: ------------------------------------------- Jan 27 11:27:08 volumio volumio[2961]: info: MYVOLUMIO Environment detected Jan 27 11:27:08 volumio volumio[2961]: info: Plugin folders cleanup Jan 27 11:27:08 volumio volumio[2961]: info: Scanning into folder /volumio/app/plugins/ Jan 27 11:27:08 volumio volumio[2961]: info: Scanning category audio_interface Jan 27 11:27:08 volumio volumio[2961]: info: Scanning category miscellanea Jan 27 11:27:08 volumio volumio[2961]: info: Scanning category music_service Jan 27 11:27:08 volumio volumio[2961]: info: Scanning category plugins.json Jan 27 11:27:08 volumio volumio[2961]: info: Scanning category system_controller Jan 27 11:27:08 volumio volumio[2961]: info: Scanning category user_interface Jan 27 11:27:08 volumio volumio[2961]: info: Scanning into folder /data/plugins/ Jan 27 11:27:08 volumio volumio[2961]: info: Scanning category music_service Jan 27 11:27:08 volumio volumio[2961]: info: Plugin folders cleanup completed Jan 27 11:27:08 volumio volumio[2961]: info: ------------------------------------------- Jan 27 11:27:08 volumio volumio[2961]: info: ----- Core plugins startup ---- Jan 27 11:27:08 volumio volumio[2961]: info: ------------------------------------------- Jan 27 11:27:08 volumio volumio[2961]: info: Loading plugins from folder /volumio/app/plugins/ Jan 27 11:27:08 volumio volumio[2961]: info: Adding plugin upnp to MyMusic Plugins Jan 27 11:27:08 volumio volumio[2961]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 27 11:27:08 volumio volumio[2961]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 27 11:27:08 volumio volumio[2961]: info: Loading plugins from folder /data/plugins/ Jan 27 11:27:08 volumio volumio[2961]: info: Loading plugin "system"... Jan 27 11:27:08 volumio volumio[2961]: info: Loading plugin "appearance"... Jan 27 11:27:09 volumio volumio[2961]: info: Loading plugin "network"... Jan 27 11:27:09 volumio volumio[2961]: info: Refreshing Cached IP Addresses Jan 27 11:27:09 volumio sudo[2991]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 27 11:27:09 volumio sudo[2990]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 27 11:27:09 volumio sudo[2990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:27:09 volumio sudo[2991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:27:09 volumio sudo[2990]: pam_unix(sudo:session): session closed for user root Jan 27 11:27:09 volumio volumio[2961]: info: Loading plugin "services"... Jan 27 11:27:09 volumio volumio[2961]: info: Loading plugin "alsa_controller"... Jan 27 11:27:09 volumio sudo[2991]: pam_unix(sudo:session): session closed for user root Jan 27 11:27:09 volumio sudo[2998]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 27 11:27:09 volumio sudo[2998]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:27:09 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 27 11:27:09 volumio volumio[2961]: info: Loading plugin "wizard"... Jan 27 11:27:09 volumio volumio[2961]: info: Loading plugin "networkfs"... Jan 27 11:27:09 volumio volumio[2961]: info: Starting Udev Watcher for removable devices Jan 27 11:27:09 volumio volumio[2961]: info: Ignoring mount for partition: boot Jan 27 11:27:09 volumio volumio[2961]: info: Ignoring mount for partition: volumio Jan 27 11:27:09 volumio volumio[2961]: info: Ignoring mount for partition: volumio_data Jan 27 11:27:09 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 27 11:27:09 volumio volumio[2961]: info: Loading plugin "volumio_command_line_client"... Jan 27 11:27:09 volumio volumio[2961]: info: Loading plugin "upnp"... Jan 27 11:27:09 volumio volumio[2961]: info: [1769513229568] Starting Upmpd Daemon Jan 27 11:27:09 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 27 11:27:09 volumio volumio[2961]: info: Loading plugin "my_music"... Jan 27 11:27:09 volumio volumio[2961]: info: Loading plugin "mpd"... Jan 27 11:27:10 volumio volumio[2961]: info: Loading plugin "upnp_browser"... Jan 27 11:27:11 volumio volumio-remote-updater[820]: [2026-01-27 11:27:11] [connect] Successful connection Jan 27 11:27:11 volumio volumio[2961]: info: Loading plugin "alarm-clock"... Jan 27 11:27:11 volumio volumio[2961]: info: Loading plugin "airplay_emulation"... Jan 27 11:27:11 volumio volumio[2961]: info: Starting Shairport Sync Jan 27 11:27:11 volumio volumio[2961]: info: Loading plugin "last_100"... Jan 27 11:27:11 volumio volumio[2961]: info: Loading plugin "webradio"... Jan 27 11:27:11 volumio volumio[2961]: info: Loading plugin "i2s_dacs"... Jan 27 11:27:11 volumio volumio[2961]: info: Loading plugin "volumiodiscovery"... Jan 27 11:27:11 volumio volumio[2961]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 27 11:27:11 volumio volumio[2961]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 27 11:27:11 volumio node[2961]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 27 11:27:11 volumio volumio[2961]: *** WARNING *** For more information see Jan 27 11:27:11 volumio volumio[2961]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 27 11:27:11 volumio volumio[2961]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 27 11:27:11 volumio volumio[2961]: *** WARNING *** For more information see Jan 27 11:27:11 volumio node[2961]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 27 11:27:11 volumio node[2961]: *** WARNING *** For more information see Jan 27 11:27:11 volumio node[2961]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 27 11:27:11 volumio node[2961]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 27 11:27:11 volumio node[2961]: *** WARNING *** For more information see Jan 27 11:27:11 volumio volumio[2961]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 27 11:27:11 volumio volumio[2961]: info: Discovery: Started advertising with name: Volumio Jan 27 11:27:11 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 27 11:27:11 volumio volumio[2961]: info: Loading plugin "squeezelite_mc"... Jan 27 11:27:11 volumio sudo[2998]: pam_unix(sudo:session): session closed for user root Jan 27 11:27:12 volumio volumio[2961]: info: Loading plugin "outputs"... Jan 27 11:27:12 volumio volumio[2961]: info: Loading plugin "albumart"... Jan 27 11:27:12 volumio volumio[2961]: info: Plugin example_plugin is not enabled Jan 27 11:27:12 volumio volumio[2961]: info: Loading plugin "inputs"... Jan 27 11:27:12 volumio volumio[2961]: info: Loading plugin "updater_comm"... Jan 27 11:27:12 volumio volumio[2961]: info: Plugin mpdemulation is not enabled Jan 27 11:27:12 volumio volumio[2961]: info: Loading plugin "rest_api"... Jan 27 11:27:12 volumio volumio[2961]: info: Loading plugin "websocket"... Jan 27 11:27:12 volumio volumio[2961]: info: Starting Socket.io Server version 1.7.4 Jan 27 11:27:12 volumio volumio[2961]: info: Loading i18n strings for locale en Jan 27 11:27:12 volumio volumio[2961]: Updating browse sources language Jan 27 11:27:12 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 11:27:12 volumio dhcpcd[902]: eth0: carrier acquired Jan 27 11:27:12 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Jan 27 11:27:12 volumio dhcpcd[902]: eth0: IAID 32:48:08:26 Jan 27 11:27:12 volumio dhcpcd[902]: eth0: adding address fe80::8811:6426:b6b0:eab2 Jan 27 11:27:12 volumio dhcpcd[902]: ipv6_addaddr1: Permission denied Jan 27 11:27:13 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:27:13 volumio volumio[2961]: info: CoreCommandRouter::initPlayerControls Jan 27 11:27:13 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:27:13 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:27:13 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:27:13 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:27:13 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:27:13 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:27:13 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:27:13 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:27:13 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:27:13 volumio volumio[2961]: Express server listening on port 3000 Jan 27 11:27:13 volumio volumio[2961]: [Metrics] WebUI: 5s 688.77ms Jan 27 11:27:13 volumio volumio[2961]: info: CoreStateMachine::resetVolumioState Jan 27 11:27:13 volumio volumio[2961]: info: CoreStateMachine::getcurrentVolume Jan 27 11:27:13 volumio volumio[2961]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 11:27:13 volumio volumio[2961]: info: Cannot read play queue from file Jan 27 11:27:13 volumio dhcpcd[902]: eth0: soliciting an IPv6 router Jan 27 11:27:13 volumio volumio[2961]: info: Volumio Network Manager: Network status updated: 0 Jan 27 11:27:13 volumio ifplugd(eth0)[1031]: Link beat detected. Jan 27 11:27:13 volumio volumio[3024]: Forking 3 albumart workers Jan 27 11:27:13 volumio ifplugd(eth0)[1031]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jan 27 11:27:13 volumio volumio-remote-updater[820]: [2026-01-27 11:27:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1769513231 101 Jan 27 11:27:13 volumio ifplugd(eth0)[1031]: client: sending commands to dhcpcd process Jan 27 11:27:13 volumio dhcpcd[902]: control command: dhcpcd eth0 Jan 27 11:27:13 volumio dhcpcd[902]: control_free: No such file or directory Jan 27 11:27:13 volumio volumio[2961]: 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: 1 Jan 27 11:27:13 volumio dhcpcd[902]: eth0: soliciting a DHCP lease Jan 27 11:27:13 volumio volumio[2961]: info: VolumeController:: Volume=100 Mute =false Jan 27 11:27:13 volumio volumio[2961]: info: CoreStateMachine::pushState Jan 27 11:27:13 volumio volumio[2961]: info: CorePlayQueue::getTrack 0 Jan 27 11:27:13 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 11:27:13 volumio volumio[2961]: info: CoreCommandRouter::volumioPushState Jan 27 11:27:13 volumio volumio[2961]: info: CoreStateMachine::updateTrackBlock Jan 27 11:27:13 volumio volumio[2961]: info: CorePlayQueue::getTrackBlock Jan 27 11:27:13 volumio volumio[2961]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 11:27:13 volumio ifplugd(eth0)[1031]: Program executed successfully. Jan 27 11:27:13 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 11:27:13 volumio volumio[2961]: info: CoreStateMachine::setRepeat null single undefined Jan 27 11:27:13 volumio volumio[2961]: info: CoreStateMachine::pushState Jan 27 11:27:13 volumio volumio[2961]: info: CorePlayQueue::getTrack 0 Jan 27 11:27:13 volumio volumio[2961]: info: CoreCommandRouter::volumioPushState Jan 27 11:27:13 volumio volumio[2961]: info: CoreStateMachine::setRandom null Jan 27 11:27:13 volumio volumio[2961]: info: CoreStateMachine::pushState Jan 27 11:27:13 volumio volumio[2961]: info: CorePlayQueue::getTrack 0 Jan 27 11:27:13 volumio volumio[2961]: info: CoreCommandRouter::volumioPushState Jan 27 11:27:14 volumio volumio[2961]: info: Setting Device type: Raspberry PI Jan 27 11:27:14 volumio volumio[2961]: info: Completed loading Core Plugins Jan 27 11:27:14 volumio volumio[2961]: info: Preparing to generate the ALSA configuration file Jan 27 11:27:14 volumio volumio[2961]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 27 11:27:14 volumio volumio[2961]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Jan 27 11:27:14 volumio volumio[2961]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 27 11:27:14 volumio volumio[2961]: info: Discovery: adding 5fa55872-f14f-4905-9340-fa825c86bd20 Jan 27 11:27:14 volumio volumio[2961]: info: Discovery: Found device Volumio Jan 27 11:27:14 volumio volumio[2961]: info: CoreCommandRouter::volumioGetState Jan 27 11:27:14 volumio volumio[2961]: info: CorePlayQueue::getTrack 0 Jan 27 11:27:14 volumio volumio[2961]: info: VolumeController:: Volume=100 Mute =false Jan 27 11:27:14 volumio volumio[2961]: info: CoreStateMachine::pushState Jan 27 11:27:14 volumio volumio[2961]: info: CorePlayQueue::getTrack 0 Jan 27 11:27:14 volumio volumio[2961]: info: CoreCommandRouter::volumioPushState Jan 27 11:27:14 volumio volumio[2961]: info: Asound.conf file unchanged, so no further update is needed Jan 27 11:27:14 volumio volumio[2961]: info: Output device has changed, restarting MPD Jan 27 11:27:14 volumio volumio[2961]: info: Output device has changed, restarting Shairport Sync Jan 27 11:27:14 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:27:14 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 11:27:14 volumio sudo[3129]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 27 11:27:14 volumio sudo[3129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:27:14 volumio sudo[3127]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 27 11:27:14 volumio sudo[3127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:27:14 volumio sudo[3127]: pam_unix(sudo:session): session closed for user root Jan 27 11:27:14 volumio volumio[2961]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 11:27:14 volumio volumio[2961]: info: ___________ START PLUGINS ___________ Jan 27 11:27:14 volumio volumio[2961]: info: ControllerMpd::onStart: Initializing MPD Jan 27 11:27:14 volumio volumio[2961]: info: Creating MPD Configuration file Jan 27 11:27:14 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 27 11:27:14 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:27:14 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 27 11:27:14 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 27 11:27:14 volumio systemd[1]: mpd.service: Consumed 1.487s CPU time. Jan 27 11:27:14 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 27 11:27:14 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 27 11:27:14 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 27 11:27:14 volumio volumio[2961]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 27 11:27:14 volumio volumio[2961]: info: [1769513234765] CoreMusicLibrary::Adding element Media Servers Jan 27 11:27:14 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 11:27:14 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 27 11:27:14 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 27 11:27:14 volumio sudo[3137]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 27 11:27:14 volumio sudo[3137]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:27:14 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:27:14 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 11:27:14 volumio sudo[3137]: pam_unix(sudo:session): session closed for user root Jan 27 11:27:14 volumio sudo[3140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 27 11:27:14 volumio sudo[3141]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 27 11:27:14 volumio sudo[3141]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 27 11:27:14 volumio sudo[3140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:27:14 volumio sudo[3141]: pam_unix(sudo:session): session closed for user root Jan 27 11:27:14 volumio volumio[2961]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 11:27:14 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:27:14 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 11:27:15 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 27 11:27:15 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 27 11:27:15 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 27 11:27:15 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 27 11:27:15 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 27 11:27:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 27 11:27:15 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 27 11:27:15 volumio volumio[2961]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 11:27:15 volumio volumio[2961]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 27 11:27:15 volumio volumio[2961]: info: [1769513235166] CoreMusicLibrary::Adding element Last_100 Jan 27 11:27:15 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 11:27:15 volumio sudo[3155]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 27 11:27:15 volumio sudo[3155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 27 11:27:15 volumio sudo[3155]: pam_unix(sudo:session): session closed for user root Jan 27 11:27:15 volumio volumio[2961]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 27 11:27:15 volumio volumio[2961]: info: [1769513235210] CoreMusicLibrary::Adding element Webradio Jan 27 11:27:15 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 11:27:15 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 27 11:27:15 volumio volumio[2961]: info: Initializing BBC Radios Jan 27 11:27:15 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 27 11:27:15 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 11:27:15 volumio volumio[2961]: info: [squeezelite_mc] Starting proxy server... Jan 27 11:27:15 volumio volumio[2961]: info: Volumio Calling Home Jan 27 11:27:16 volumio volumio[2961]: info: [squeezelite_mc] Proxy server started on port 39049 Jan 27 11:27:16 volumio mpd[3157]: 2026-01-27T11:27:16 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 27 11:27:16 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 27 11:27:16 volumio sudo[3140]: pam_unix(sudo:session): session closed for user root Jan 27 11:27:16 volumio sudo[3129]: pam_unix(sudo:session): session closed for user root Jan 27 11:27:17 volumio dhcpcd[902]: eth0: offered 192.168.1.131 from 192.168.1.1 Jan 27 11:27:17 volumio dhcpcd[902]: eth0: probing address 192.168.1.131/24 Jan 27 11:27:18 volumio volumio[3045]: Starting albumart workers Jan 27 11:27:18 volumio volumio[2961]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 11:27:18 volumio volumio[2961]: Unhandled rejection Error: No sockets available, cannot start. Jan 27 11:27:18 volumio volumio[2961]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 27 11:27:18 volumio volumio[2961]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 27 11:27:18 volumio volumio[2961]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 27 11:27:18 volumio volumio[2961]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 27 11:27:18 volumio volumio[2961]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 27 11:27:18 volumio volumio[2961]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 27 11:27:18 volumio volumio[2961]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 27 11:27:18 volumio volumio[2961]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 27 11:27:18 volumio volumio[2961]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 27 11:27:18 volumio volumio[2961]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 27 11:27:18 volumio volumio[2961]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 27 11:27:18 volumio volumio[2961]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 27 11:27:18 volumio volumio[2961]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 27 11:27:18 volumio volumio[2961]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 27 11:27:18 volumio volumio[2961]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 27 11:27:18 volumio volumio[2961]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 27 11:27:18 volumio volumio[2961]: info: MPD Permissions set Jan 27 11:27:18 volumio volumio[2961]: info: MPD Permissions set Jan 27 11:27:19 volumio volumio[2961]: info: VolumeController:: Volume=100 Mute =false Jan 27 11:27:19 volumio volumio[2961]: info: CoreStateMachine::pushState Jan 27 11:27:19 volumio volumio[2961]: info: CorePlayQueue::getTrack 0 Jan 27 11:27:19 volumio volumio[2961]: info: CoreCommandRouter::volumioPushState Jan 27 11:27:19 volumio volumio[2961]: info: [squeezelite_mc] Server discovery started Jan 27 11:27:19 volumio volumio[2961]: info: [squeezelite_mc] Player finder started Jan 27 11:27:19 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:27:19 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:27:19 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 11:27:19 volumio volumio[2961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:27:19 volumio volumio[2961]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 27 11:27:19 volumio volumio[2961]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 11:27:19 volumio volumio[2961]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 27 11:27:19 volumio volumio[2961]: at doSend (node:dgram:717:16) Jan 27 11:27:19 volumio volumio[2961]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Jan 27 11:27:19 volumio volumio[2961]: at afterDns (node:dgram:663:5) Jan 27 11:27:19 volumio volumio[2961]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Jan 27 11:27:19 volumio volumio[2961]: errno: -101, Jan 27 11:27:19 volumio volumio[2961]: code: 'ENETUNREACH', Jan 27 11:27:19 volumio volumio[2961]: syscall: 'send', Jan 27 11:27:19 volumio volumio[2961]: address: '255.255.255.255', Jan 27 11:27:19 volumio volumio[2961]: port: 3483 Jan 27 11:27:19 volumio volumio[2961]: } Jan 27 11:27:19 volumio volumio[2961]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 11:27:19 volumio volumio[3040]: Starting albumart workers Jan 27 11:27:20 volumio volumio[3053]: Starting albumart workers Jan 27 11:27:23 volumio dhcpcd[902]: eth0: leased 192.168.1.131 for 86400 seconds Jan 27 11:27:23 volumio avahi-daemon[804]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.131. Jan 27 11:27:23 volumio avahi-daemon[804]: New relevant interface eth0.IPv4 for mDNS. Jan 27 11:27:23 volumio avahi-daemon[804]: Registering new address record for 192.168.1.131 on eth0.IPv4. Jan 27 11:27:23 volumio dhcpcd[902]: eth0: adding route to 192.168.1.0/24 Jan 27 11:27:23 volumio dhcpcd[902]: eth0: adding default route via 192.168.1.1 Jan 27 11:27:23 volumio systemd[1]: welcome.service: Deactivated successfully. Jan 27 11:27:23 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 27 11:27:23 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 27 11:27:23 volumio sudo[3229]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-27 11:26' Jan 27 11:27:23 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 27 11:27:23 volumio sudo[3229]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:27:23 volumio welcome[3247]: Resolved ip:[1] 192.168.1.131 Jan 27 11:27:23 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 27 11:27:23 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. 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="3a3cfe435f8bb2bc94a920662e91a4ef35bc8e7c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 2 11:51:19 UTC 2025" VOLUMIO_VERSION="4.072" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e5001581b220b096097cf8f46a54d4f1"