-- Logs begin at Sun 2024-10-20 14:41:17 CEST, end at Thu 2025-05-22 16:56:25 CEST. -- May 22 16:55:05 vol-ws ifplugd(eth0)[805]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. May 22 16:55:05 vol-ws ifplugd(eth0)[805]: client: sending signal ALRM to pid 2021 May 22 16:55:05 vol-ws dhcpcd[2799]: sending signal ALRM to pid 2021 May 22 16:55:05 vol-ws dhcpcd[2021]: received SIGALRM, releasing May 22 16:55:05 vol-ws dhcpcd[2021]: eth0: removing interface May 22 16:55:05 vol-ws ifplugd(eth0)[805]: client: waiting for pid 2021 to exit May 22 16:55:05 vol-ws dhcpcd[2799]: waiting for pid 2021 to exit May 22 16:55:05 vol-ws dhcpcd[2021]: dhcpcd exited May 22 16:55:05 vol-ws kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup May 22 16:55:05 vol-ws ifplugd(eth0)[805]: Program executed successfully. May 22 16:55:05 vol-ws kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup May 22 16:55:05 vol-ws kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down May 22 16:55:20 vol-ws volumio[1890]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 16:55:20 vol-ws volumio[1890]: Error: send ENETUNREACH 255.255.255.255:3483 May 22 16:55:20 vol-ws volumio[1890]: at doSend (dgram.js:692:16) May 22 16:55:20 vol-ws volumio[1890]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 22 16:55:20 vol-ws volumio[1890]: at afterDns (dgram.js:638:5) May 22 16:55:20 vol-ws volumio[1890]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 22 16:55:20 vol-ws volumio[1890]: errno: -101, May 22 16:55:20 vol-ws volumio[1890]: code: 'ENETUNREACH', May 22 16:55:20 vol-ws volumio[1890]: syscall: 'send', May 22 16:55:20 vol-ws volumio[1890]: address: '255.255.255.255', May 22 16:55:20 vol-ws volumio[1890]: port: 3483 May 22 16:55:20 vol-ws volumio[1890]: } May 22 16:55:20 vol-ws volumio[1890]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 16:55:20 vol-ws sudo[2869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 16:54 May 22 16:55:20 vol-ws sudo[2869]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:20 vol-ws sudo[2869]: pam_unix(sudo:session): session closed for user root May 22 16:55:20 vol-ws volumio-remote-updater[635]: [2025-05-22 16:55:20] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 22 16:55:20 vol-ws volumio-remote-updater[635]: [2025-05-22 16:55:20] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 22 16:55:20 vol-ws systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 22 16:55:21 vol-ws systemd[1]: volumio.service: Failed with result 'exit-code'. May 22 16:55:21 vol-ws systemd[1]: Started dynamicswap service. May 22 16:55:21 vol-ws systemd[1]: dynamicswap.service: Succeeded. May 22 16:55:21 vol-ws systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 22 16:55:21 vol-ws systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. May 22 16:55:21 vol-ws systemd[1]: Stopped Volumio Backend Module. May 22 16:55:21 vol-ws systemd[1]: Started Volumio Backend Module. May 22 16:55:21 vol-ws systemd[1]: Started dynamicswap service. May 22 16:55:21 vol-ws systemd[1]: dynamicswap.service: Succeeded. May 22 16:55:23 vol-ws volumio[2883]: info: ------------------------------------------- May 22 16:55:23 vol-ws volumio[2883]: info: ----- Volumio3 ---- May 22 16:55:23 vol-ws volumio[2883]: info: ------------------------------------------- May 22 16:55:23 vol-ws volumio[2883]: info: ----- System startup ---- May 22 16:55:23 vol-ws volumio[2883]: info: ------------------------------------------- May 22 16:55:23 vol-ws volumio[2883]: info: MYVOLUMIO Environment detected May 22 16:55:23 vol-ws volumio[2883]: info: Plugin folders cleanup May 22 16:55:23 vol-ws volumio[2883]: info: Scanning into folder /volumio/app/plugins/ May 22 16:55:23 vol-ws volumio[2883]: info: Scanning category audio_interface May 22 16:55:23 vol-ws volumio[2883]: info: Scanning category miscellanea May 22 16:55:23 vol-ws volumio[2883]: info: Scanning category music_service May 22 16:55:23 vol-ws volumio[2883]: info: Scanning category plugins.json May 22 16:55:23 vol-ws volumio[2883]: info: Scanning category system_controller May 22 16:55:23 vol-ws volumio[2883]: info: Scanning category user_interface May 22 16:55:23 vol-ws volumio[2883]: info: Scanning into folder /data/plugins/ May 22 16:55:23 vol-ws volumio[2883]: info: Scanning category music_service May 22 16:55:23 vol-ws volumio[2883]: info: Plugin folders cleanup completed May 22 16:55:23 vol-ws volumio[2883]: info: ------------------------------------------- May 22 16:55:23 vol-ws volumio[2883]: info: ----- Core plugins startup ---- May 22 16:55:23 vol-ws volumio[2883]: info: ------------------------------------------- May 22 16:55:23 vol-ws volumio[2883]: info: Loading plugins from folder /volumio/app/plugins/ May 22 16:55:23 vol-ws volumio[2883]: info: Adding plugin upnp to MyMusic Plugins May 22 16:55:23 vol-ws volumio[2883]: info: Adding plugin airplay_emulation to MyMusic Plugins May 22 16:55:23 vol-ws volumio[2883]: info: Adding plugin upnp_browser to MyMusic Plugins May 22 16:55:23 vol-ws volumio[2883]: info: Loading plugins from folder /data/plugins/ May 22 16:55:23 vol-ws volumio[2883]: info: Loading plugin "system"... May 22 16:55:23 vol-ws volumio[2883]: info: Loading plugin "appearance"... May 22 16:55:24 vol-ws volumio[2883]: info: Loading plugin "network"... May 22 16:55:24 vol-ws volumio[2883]: info: Refreshing Cached IP Addresses May 22 16:55:25 vol-ws sudo[2911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 16:55:25 vol-ws sudo[2911]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:25 vol-ws sudo[2911]: pam_unix(sudo:session): session closed for user root May 22 16:55:25 vol-ws sudo[2913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 16:55:25 vol-ws volumio[2883]: info: Loading plugin "services"... May 22 16:55:25 vol-ws sudo[2913]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:25 vol-ws volumio[2883]: info: Loading plugin "alsa_controller"... May 22 16:55:25 vol-ws sudo[2913]: pam_unix(sudo:session): session closed for user root May 22 16:55:25 vol-ws sudo[2916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 22 16:55:25 vol-ws sudo[2916]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:25 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:55:25 vol-ws volumio[2883]: info: Loading plugin "wizard"... May 22 16:55:25 vol-ws volumio[2883]: info: Loading plugin "networkfs"... May 22 16:55:25 vol-ws volumio[2883]: info: Cannot mount NAS audio at system boot, trial number 1 ,retrying in 5 seconds May 22 16:55:25 vol-ws volumio[2883]: info: Starting Udev Watcher for removable devices May 22 16:55:25 vol-ws volumio[2883]: info: Ignoring mount for partition: boot May 22 16:55:25 vol-ws volumio[2883]: info: Ignoring mount for partition: volumio May 22 16:55:25 vol-ws volumio[2883]: info: Ignoring mount for partition: volumio_data May 22 16:55:25 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 16:55:25 vol-ws volumio[2883]: info: Loading plugin "volumio_command_line_client"... May 22 16:55:25 vol-ws volumio[2883]: info: Plugin upnp is not enabled May 22 16:55:25 vol-ws volumio[2883]: info: Loading plugin "my_music"... May 22 16:55:25 vol-ws volumio[2883]: info: Loading plugin "mpd"... May 22 16:55:25 vol-ws volumio[2883]: info: Plugin upnp_browser is not enabled May 22 16:55:25 vol-ws volumio[2883]: info: Loading plugin "alarm-clock"... May 22 16:55:25 vol-ws volumio[2883]: info: Plugin airplay_emulation is not enabled May 22 16:55:25 vol-ws volumio[2883]: info: Loading plugin "last_100"... May 22 16:55:25 vol-ws volumio[2883]: info: Loading plugin "webradio"... May 22 16:55:25 vol-ws volumio[2883]: info: Loading plugin "i2s_dacs"... May 22 16:55:25 vol-ws volumio[2883]: info: Loading plugin "volumiodiscovery"... May 22 16:55:25 vol-ws volumio[2883]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 16:55:25 vol-ws node[2883]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 16:55:25 vol-ws volumio[2883]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:55:25 vol-ws node[2883]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:55:25 vol-ws volumio[2883]: *** WARNING *** For more information see May 22 16:55:25 vol-ws node[2883]: *** WARNING *** For more information see May 22 16:55:25 vol-ws volumio[2883]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 16:55:25 vol-ws node[2883]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 16:55:25 vol-ws volumio[2883]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:55:25 vol-ws node[2883]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:55:25 vol-ws volumio[2883]: *** WARNING *** For more information see May 22 16:55:25 vol-ws node[2883]: *** WARNING *** For more information see May 22 16:55:25 vol-ws volumio[2883]: info: Applying required configuration parameters for plugin volumiodiscovery May 22 16:55:25 vol-ws volumio[2883]: info: Discovery: Started advertising with name: Vol-WS May 22 16:55:25 vol-ws sudo[2916]: pam_unix(sudo:session): session closed for user root May 22 16:55:25 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 16:55:25 vol-ws volumio[2883]: info: Loading plugin "squeezelite_mc"... May 22 16:55:25 vol-ws volumio-remote-updater[635]: [2025-05-22 16:55:25] [connect] Successful connection May 22 16:55:26 vol-ws volumio[2883]: info: Loading plugin "outputs"... May 22 16:55:26 vol-ws volumio[2883]: info: Loading plugin "albumart"... May 22 16:55:26 vol-ws volumio[2883]: info: Plugin example_plugin is not enabled May 22 16:55:26 vol-ws volumio[2883]: info: Loading plugin "inputs"... May 22 16:55:26 vol-ws volumio[2883]: info: Loading plugin "updater_comm"... May 22 16:55:27 vol-ws volumio[2883]: info: Plugin mpdemulation is not enabled May 22 16:55:27 vol-ws volumio[2883]: info: Loading plugin "rest_api"... May 22 16:55:27 vol-ws volumio[2883]: info: Loading plugin "websocket"... May 22 16:55:27 vol-ws volumio[2883]: info: Starting Socket.io Server version 2.3.0 May 22 16:55:27 vol-ws volumio[2883]: info: Loading i18n strings for locale en May 22 16:55:27 vol-ws volumio[2883]: Updating browse sources language May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:55:27 vol-ws volumio[2883]: Forking 3 albumart workers May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::initPlayerControls May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 16:55:27 vol-ws volumio[2883]: Express server listening on port 3000 May 22 16:55:27 vol-ws volumio[2883]: [Metrics] WebUI: 4s 800.99ms May 22 16:55:27 vol-ws volumio[2883]: info: CoreStateMachine::resetVolumioState May 22 16:55:27 vol-ws volumio[2883]: info: CoreStateMachine::getcurrentVolume May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:55:27 vol-ws volumio[2883]: info: Cannot read play queue from file May 22 16:55:27 vol-ws volumio-remote-updater[635]: [2025-05-22 16:55:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747925725 101 May 22 16:55:27 vol-ws volumio[2883]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 May 22 16:55:27 vol-ws volumio[2883]: info: CoreStateMachine::pushState May 22 16:55:27 vol-ws volumio[2883]: info: CorePlayQueue::getTrack 0 May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::volumioPushState May 22 16:55:27 vol-ws volumio[2883]: info: CoreStateMachine::updateTrackBlock May 22 16:55:27 vol-ws volumio[2883]: info: CorePlayQueue::getTrackBlock May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:55:27 vol-ws volumio[2883]: info: Executing start script for DAC IQaudIO DAC Plus May 22 16:55:27 vol-ws volumio[2883]: info: Setting Device type: Raspberry PI May 22 16:55:27 vol-ws volumio[2883]: info: CoreStateMachine::setRepeat null single undefined May 22 16:55:27 vol-ws volumio[2883]: info: CoreStateMachine::pushState May 22 16:55:27 vol-ws volumio[2883]: info: CorePlayQueue::getTrack 0 May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::volumioPushState May 22 16:55:27 vol-ws volumio[2883]: info: CoreStateMachine::setRandom null May 22 16:55:27 vol-ws volumio[2883]: info: CoreStateMachine::pushState May 22 16:55:27 vol-ws volumio[2883]: info: CorePlayQueue::getTrack 0 May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::volumioPushState May 22 16:55:27 vol-ws volumio[2883]: info: CoreStateMachine::pushState May 22 16:55:27 vol-ws volumio[2883]: info: CorePlayQueue::getTrack 0 May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::volumioPushState May 22 16:55:27 vol-ws volumio[2883]: info: DAC script executed May 22 16:55:27 vol-ws volumio[2883]: info: Completed loading Core Plugins May 22 16:55:27 vol-ws volumio[2883]: info: Preparing to generate the ALSA configuration file May 22 16:55:27 vol-ws volumio[2883]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 22 16:55:27 vol-ws volumio[2883]: info: Reading ALSA contributions from plugins. May 22 16:55:27 vol-ws volumio[2883]: info: Discovery: adding 544166b9-d1dd-4d34-96ac-979983e6a685 May 22 16:55:27 vol-ws volumio[2883]: info: Discovery: Found device Vol-WS May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::volumioGetState May 22 16:55:27 vol-ws volumio[2883]: info: CorePlayQueue::getTrack 0 May 22 16:55:27 vol-ws volumio[2883]: info: Asound.conf file unchanged, so no further update is needed May 22 16:55:27 vol-ws volumio[2883]: info: Output device has changed, restarting MPD May 22 16:55:27 vol-ws volumio[2883]: info: ___________ START PLUGINS ___________ May 22 16:55:27 vol-ws volumio[2883]: info: ControllerMpd::onStart: Initializing MPD May 22 16:55:27 vol-ws volumio[2883]: info: Creating MPD Configuration file May 22 16:55:27 vol-ws sudo[2977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 16:55:27 vol-ws sudo[2977]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:27 vol-ws sudo[2977]: pam_unix(sudo:session): session closed for user root May 22 16:55:27 vol-ws sudo[2978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 16:55:27 vol-ws sudo[2978]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 16:55:27 vol-ws volumio[2883]: info: [1747925727949] CoreMusicLibrary::Adding element Last_100 May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 16:55:27 vol-ws volumio[2883]: info: [1747925727957] CoreMusicLibrary::Adding element Webradio May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:55:27 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:55:27 vol-ws volumio[2883]: info: Initializing BBC Radios May 22 16:55:27 vol-ws sudo[2982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 16:55:27 vol-ws sudo[2982]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:28 vol-ws systemd[1]: Stopping Music Player Daemon... May 22 16:55:28 vol-ws sudo[2982]: pam_unix(sudo:session): session closed for user root May 22 16:55:28 vol-ws sudo[2984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 16:55:28 vol-ws sudo[2984]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:28 vol-ws systemd[1]: mpd.service: Succeeded. May 22 16:55:28 vol-ws systemd[1]: Stopped Music Player Daemon. May 22 16:55:28 vol-ws systemd[1]: Starting Music Player Daemon... May 22 16:55:28 vol-ws systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 22 16:55:28 vol-ws systemd[1]: mpd.service: Succeeded. May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:55:28 vol-ws systemd[1]: Stopped Music Player Daemon. May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:55:28 vol-ws systemd[1]: Starting Music Player Daemon... May 22 16:55:28 vol-ws volumio[2883]: info: [squeezelite_mc] Starting proxy server... May 22 16:55:28 vol-ws volumio[2883]: info: Volumio Calling Home May 22 16:55:28 vol-ws sudo[2998]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 16:55:28 vol-ws sudo[2998]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:28 vol-ws sudo[2998]: pam_unix(sudo:session): session closed for user root May 22 16:55:28 vol-ws volumio[2883]: Starting albumart workers May 22 16:55:28 vol-ws volumio[2883]: Starting albumart workers May 22 16:55:28 vol-ws volumio[2883]: info: [squeezelite_mc] Proxy server started on port 46845 May 22 16:55:28 vol-ws volumio[2883]: Starting albumart workers May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:55:28 vol-ws volumio[2883]: info: MPD Permissions set May 22 16:55:28 vol-ws volumio[2883]: info: MPD Permissions set May 22 16:55:28 vol-ws volumio[2883]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:55:28 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:29 vol-ws volumio[2883]: info: CoreStateMachine::pushState May 22 16:55:29 vol-ws volumio[2883]: info: CorePlayQueue::getTrack 0 May 22 16:55:29 vol-ws volumio[2883]: info: CoreCommandRouter::volumioPushState May 22 16:55:29 vol-ws volumio[2883]: info: [squeezelite_mc] Server discovery started May 22 16:55:29 vol-ws volumio[2883]: info: [squeezelite_mc] Player finder started May 22 16:55:29 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:29 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:29 vol-ws volumio[2883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:29 vol-ws volumio[2883]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 22 16:55:29 vol-ws volumio[2883]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 16:55:29 vol-ws volumio[2883]: Error: send ENETUNREACH 255.255.255.255:3483 May 22 16:55:29 vol-ws volumio[2883]: at doSend (dgram.js:692:16) May 22 16:55:29 vol-ws volumio[2883]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 22 16:55:29 vol-ws volumio[2883]: at afterDns (dgram.js:638:5) May 22 16:55:29 vol-ws volumio[2883]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 22 16:55:29 vol-ws volumio[2883]: errno: -101, May 22 16:55:29 vol-ws volumio[2883]: code: 'ENETUNREACH', May 22 16:55:29 vol-ws volumio[2883]: syscall: 'send', May 22 16:55:29 vol-ws volumio[2883]: address: '255.255.255.255', May 22 16:55:29 vol-ws volumio[2883]: port: 3483 May 22 16:55:29 vol-ws volumio[2883]: } May 22 16:55:29 vol-ws volumio[2883]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 16:55:29 vol-ws kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 May 22 16:55:29 vol-ws sudo[3054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 16:54 May 22 16:55:29 vol-ws sudo[3054]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:30 vol-ws sudo[3054]: pam_unix(sudo:session): session closed for user root May 22 16:55:30 vol-ws volumio-remote-updater[635]: [2025-05-22 16:55:30] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 22 16:55:30 vol-ws volumio-remote-updater[635]: [2025-05-22 16:55:30] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 22 16:55:30 vol-ws systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 22 16:55:30 vol-ws sudo[2984]: pam_unix(sudo:session): session closed for user root May 22 16:55:30 vol-ws sudo[2978]: pam_unix(sudo:session): session closed for user root May 22 16:55:30 vol-ws systemd[1]: volumio.service: Failed with result 'exit-code'. May 22 16:55:30 vol-ws systemd[1]: Started dynamicswap service. May 22 16:55:30 vol-ws systemd[1]: dynamicswap.service: Succeeded. May 22 16:55:30 vol-ws mpd[3003]: May 22 16:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 22 16:55:30 vol-ws systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 22 16:55:30 vol-ws systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. May 22 16:55:30 vol-ws systemd[1]: Started dynamicswap service. May 22 16:55:30 vol-ws systemd[1]: Stopped Volumio Backend Module. May 22 16:55:30 vol-ws systemd[1]: Started Volumio Backend Module. May 22 16:55:30 vol-ws systemd[1]: Started Music Player Daemon. May 22 16:55:30 vol-ws systemd[1]: dynamicswap.service: Succeeded. May 22 16:55:32 vol-ws volumio[3071]: info: ------------------------------------------- May 22 16:55:32 vol-ws volumio[3071]: info: ----- Volumio3 ---- May 22 16:55:32 vol-ws volumio[3071]: info: ------------------------------------------- May 22 16:55:32 vol-ws volumio[3071]: info: ----- System startup ---- May 22 16:55:32 vol-ws volumio[3071]: info: ------------------------------------------- May 22 16:55:33 vol-ws volumio[3071]: info: MYVOLUMIO Environment detected May 22 16:55:33 vol-ws volumio[3071]: info: Plugin folders cleanup May 22 16:55:33 vol-ws volumio[3071]: info: Scanning into folder /volumio/app/plugins/ May 22 16:55:33 vol-ws volumio[3071]: info: Scanning category audio_interface May 22 16:55:33 vol-ws volumio[3071]: info: Scanning category miscellanea May 22 16:55:33 vol-ws volumio[3071]: info: Scanning category music_service May 22 16:55:33 vol-ws volumio[3071]: info: Scanning category plugins.json May 22 16:55:33 vol-ws volumio[3071]: info: Scanning category system_controller May 22 16:55:33 vol-ws volumio[3071]: info: Scanning category user_interface May 22 16:55:33 vol-ws volumio[3071]: info: Scanning into folder /data/plugins/ May 22 16:55:33 vol-ws volumio[3071]: info: Scanning category music_service May 22 16:55:33 vol-ws volumio[3071]: info: Plugin folders cleanup completed May 22 16:55:33 vol-ws volumio[3071]: info: ------------------------------------------- May 22 16:55:33 vol-ws volumio[3071]: info: ----- Core plugins startup ---- May 22 16:55:33 vol-ws volumio[3071]: info: ------------------------------------------- May 22 16:55:33 vol-ws volumio[3071]: info: Loading plugins from folder /volumio/app/plugins/ May 22 16:55:33 vol-ws volumio[3071]: info: Adding plugin upnp to MyMusic Plugins May 22 16:55:33 vol-ws volumio[3071]: info: Adding plugin airplay_emulation to MyMusic Plugins May 22 16:55:33 vol-ws volumio[3071]: info: Adding plugin upnp_browser to MyMusic Plugins May 22 16:55:33 vol-ws volumio[3071]: info: Loading plugins from folder /data/plugins/ May 22 16:55:33 vol-ws volumio[3071]: info: Loading plugin "system"... May 22 16:55:33 vol-ws volumio[3071]: info: Loading plugin "appearance"... May 22 16:55:34 vol-ws volumio[3071]: info: Loading plugin "network"... May 22 16:55:34 vol-ws volumio[3071]: info: Refreshing Cached IP Addresses May 22 16:55:34 vol-ws sudo[3097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 16:55:34 vol-ws sudo[3097]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:34 vol-ws sudo[3097]: pam_unix(sudo:session): session closed for user root May 22 16:55:34 vol-ws sudo[3099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 16:55:34 vol-ws sudo[3099]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:34 vol-ws volumio[3071]: info: Loading plugin "services"... May 22 16:55:34 vol-ws sudo[3099]: pam_unix(sudo:session): session closed for user root May 22 16:55:34 vol-ws volumio[3071]: info: Loading plugin "alsa_controller"... May 22 16:55:34 vol-ws sudo[3102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 22 16:55:34 vol-ws sudo[3102]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:34 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:55:34 vol-ws volumio[3071]: info: Loading plugin "wizard"... May 22 16:55:34 vol-ws volumio[3071]: info: Loading plugin "networkfs"... May 22 16:55:34 vol-ws volumio[3071]: info: Cannot mount NAS audio at system boot, trial number 1 ,retrying in 5 seconds May 22 16:55:34 vol-ws volumio[3071]: info: Starting Udev Watcher for removable devices May 22 16:55:34 vol-ws volumio[3071]: info: Ignoring mount for partition: boot May 22 16:55:34 vol-ws volumio[3071]: info: Ignoring mount for partition: volumio May 22 16:55:34 vol-ws volumio[3071]: info: Ignoring mount for partition: volumio_data May 22 16:55:34 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 16:55:34 vol-ws volumio[3071]: info: Loading plugin "volumio_command_line_client"... May 22 16:55:34 vol-ws volumio[3071]: info: Plugin upnp is not enabled May 22 16:55:34 vol-ws volumio[3071]: info: Loading plugin "my_music"... May 22 16:55:34 vol-ws volumio[3071]: info: Loading plugin "mpd"... May 22 16:55:34 vol-ws volumio[3071]: info: Plugin upnp_browser is not enabled May 22 16:55:34 vol-ws volumio[3071]: info: Loading plugin "alarm-clock"... May 22 16:55:35 vol-ws volumio[3071]: info: Plugin airplay_emulation is not enabled May 22 16:55:35 vol-ws volumio[3071]: info: Loading plugin "last_100"... May 22 16:55:35 vol-ws volumio[3071]: info: Loading plugin "webradio"... May 22 16:55:35 vol-ws volumio-remote-updater[635]: [2025-05-22 16:55:35] [connect] Successful connection May 22 16:55:35 vol-ws volumio[3071]: info: Loading plugin "i2s_dacs"... May 22 16:55:35 vol-ws volumio[3071]: info: Loading plugin "volumiodiscovery"... May 22 16:55:35 vol-ws volumio[3071]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 16:55:35 vol-ws volumio[3071]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:55:35 vol-ws volumio[3071]: *** WARNING *** For more information see May 22 16:55:35 vol-ws node[3071]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 16:55:35 vol-ws volumio[3071]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 16:55:35 vol-ws volumio[3071]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:55:35 vol-ws volumio[3071]: *** WARNING *** For more information see May 22 16:55:35 vol-ws node[3071]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:55:35 vol-ws node[3071]: *** WARNING *** For more information see May 22 16:55:35 vol-ws node[3071]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 16:55:35 vol-ws node[3071]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:55:35 vol-ws node[3071]: *** WARNING *** For more information see May 22 16:55:35 vol-ws volumio[3071]: info: Applying required configuration parameters for plugin volumiodiscovery May 22 16:55:35 vol-ws volumio[3071]: info: Discovery: Started advertising with name: Vol-WS May 22 16:55:35 vol-ws sudo[3102]: pam_unix(sudo:session): session closed for user root May 22 16:55:35 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 16:55:35 vol-ws volumio[3071]: info: Loading plugin "squeezelite_mc"... May 22 16:55:36 vol-ws volumio[3071]: info: Loading plugin "outputs"... May 22 16:55:36 vol-ws volumio[3071]: info: Loading plugin "albumart"... May 22 16:55:36 vol-ws volumio[3071]: info: Plugin example_plugin is not enabled May 22 16:55:36 vol-ws volumio[3071]: info: Loading plugin "inputs"... May 22 16:55:36 vol-ws volumio[3071]: info: Loading plugin "updater_comm"... May 22 16:55:36 vol-ws volumio[3071]: info: Plugin mpdemulation is not enabled May 22 16:55:36 vol-ws volumio[3071]: info: Loading plugin "rest_api"... May 22 16:55:36 vol-ws volumio[3071]: info: Loading plugin "websocket"... May 22 16:55:36 vol-ws volumio[3071]: info: Starting Socket.io Server version 2.3.0 May 22 16:55:36 vol-ws volumio[3071]: info: Loading i18n strings for locale en May 22 16:55:36 vol-ws volumio[3071]: Updating browse sources language May 22 16:55:36 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:55:36 vol-ws volumio[3071]: Forking 3 albumart workers May 22 16:55:36 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 16:55:36 vol-ws volumio[3071]: info: CoreCommandRouter::initPlayerControls May 22 16:55:36 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:36 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:36 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:36 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:36 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:36 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:36 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:36 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:36 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 16:55:36 vol-ws volumio[3071]: Express server listening on port 3000 May 22 16:55:36 vol-ws volumio[3071]: [Metrics] WebUI: 4s 850.99ms May 22 16:55:36 vol-ws volumio[3071]: info: CoreStateMachine::resetVolumioState May 22 16:55:36 vol-ws volumio[3071]: info: CoreStateMachine::getcurrentVolume May 22 16:55:36 vol-ws volumio[3071]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:55:36 vol-ws volumio[3071]: info: Cannot read play queue from file May 22 16:55:36 vol-ws volumio-remote-updater[635]: [2025-05-22 16:55:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747925735 101 May 22 16:55:36 vol-ws volumio[3071]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 May 22 16:55:36 vol-ws volumio[3071]: info: CoreStateMachine::pushState May 22 16:55:36 vol-ws volumio[3071]: info: CorePlayQueue::getTrack 0 May 22 16:55:36 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 16:55:36 vol-ws volumio[3071]: info: CoreCommandRouter::volumioPushState May 22 16:55:36 vol-ws volumio[3071]: info: CoreStateMachine::updateTrackBlock May 22 16:55:36 vol-ws volumio[3071]: info: CorePlayQueue::getTrackBlock May 22 16:55:36 vol-ws volumio[3071]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:55:37 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:55:37 vol-ws volumio[3071]: info: Executing start script for DAC IQaudIO DAC Plus May 22 16:55:37 vol-ws volumio[3071]: info: CoreStateMachine::setRepeat null single undefined May 22 16:55:37 vol-ws volumio[3071]: info: CoreStateMachine::pushState May 22 16:55:37 vol-ws volumio[3071]: info: CorePlayQueue::getTrack 0 May 22 16:55:37 vol-ws volumio[3071]: info: CoreCommandRouter::volumioPushState May 22 16:55:37 vol-ws volumio[3071]: info: CoreStateMachine::setRandom null May 22 16:55:37 vol-ws volumio[3071]: info: CoreStateMachine::pushState May 22 16:55:37 vol-ws volumio[3071]: info: CorePlayQueue::getTrack 0 May 22 16:55:37 vol-ws volumio[3071]: info: CoreCommandRouter::volumioPushState May 22 16:55:37 vol-ws volumio[3071]: info: CoreStateMachine::pushState May 22 16:55:37 vol-ws volumio[3071]: info: CorePlayQueue::getTrack 0 May 22 16:55:37 vol-ws volumio[3071]: info: CoreCommandRouter::volumioPushState May 22 16:55:37 vol-ws volumio[3071]: info: DAC script executed May 22 16:55:37 vol-ws volumio[3071]: info: Setting Device type: Raspberry PI May 22 16:55:37 vol-ws volumio[3071]: info: Completed loading Core Plugins May 22 16:55:37 vol-ws volumio[3071]: info: Preparing to generate the ALSA configuration file May 22 16:55:37 vol-ws volumio[3071]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 22 16:55:37 vol-ws volumio[3071]: info: Reading ALSA contributions from plugins. May 22 16:55:37 vol-ws volumio[3071]: info: Asound.conf file unchanged, so no further update is needed May 22 16:55:37 vol-ws volumio[3071]: info: Output device has changed, restarting MPD May 22 16:55:37 vol-ws volumio[3071]: info: ___________ START PLUGINS ___________ May 22 16:55:37 vol-ws volumio[3071]: info: ControllerMpd::onStart: Initializing MPD May 22 16:55:37 vol-ws volumio[3071]: info: Creating MPD Configuration file May 22 16:55:37 vol-ws volumio[3071]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 16:55:37 vol-ws volumio[3071]: info: [1747925737257] CoreMusicLibrary::Adding element Last_100 May 22 16:55:37 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:55:37 vol-ws sudo[3164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 16:55:37 vol-ws sudo[3164]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:37 vol-ws volumio[3071]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 16:55:37 vol-ws volumio[3071]: info: [1747925737265] CoreMusicLibrary::Adding element Webradio May 22 16:55:37 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:55:37 vol-ws sudo[3163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 16:55:37 vol-ws sudo[3167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 16:55:37 vol-ws sudo[3163]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:37 vol-ws sudo[3167]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:37 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:55:37 vol-ws volumio[3071]: info: Initializing BBC Radios May 22 16:55:37 vol-ws sudo[3163]: pam_unix(sudo:session): session closed for user root May 22 16:55:37 vol-ws sudo[3167]: pam_unix(sudo:session): session closed for user root May 22 16:55:37 vol-ws systemd[1]: Stopping Music Player Daemon... May 22 16:55:37 vol-ws sudo[3168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 16:55:37 vol-ws sudo[3168]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:37 vol-ws systemd[1]: mpd.service: Succeeded. May 22 16:55:37 vol-ws systemd[1]: Stopped Music Player Daemon. May 22 16:55:37 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:55:37 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:55:37 vol-ws volumio[3071]: info: [squeezelite_mc] Starting proxy server... May 22 16:55:37 vol-ws systemd[1]: Starting Music Player Daemon... May 22 16:55:37 vol-ws volumio[3071]: info: Volumio Calling Home May 22 16:55:37 vol-ws systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 22 16:55:37 vol-ws systemd[1]: mpd.service: Succeeded. May 22 16:55:37 vol-ws systemd[1]: Stopped Music Player Daemon. May 22 16:55:37 vol-ws systemd[1]: Starting Music Player Daemon... May 22 16:55:37 vol-ws volumio[3071]: info: [squeezelite_mc] Proxy server started on port 44613 May 22 16:55:37 vol-ws sudo[3185]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 16:55:37 vol-ws sudo[3185]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:37 vol-ws sudo[3185]: pam_unix(sudo:session): session closed for user root May 22 16:55:37 vol-ws volumio[3071]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:55:37 vol-ws volumio[3071]: info: Discovery: adding 544166b9-d1dd-4d34-96ac-979983e6a685 May 22 16:55:37 vol-ws volumio[3071]: info: Discovery: Found device Vol-WS May 22 16:55:37 vol-ws volumio[3071]: info: CoreCommandRouter::volumioGetState May 22 16:55:37 vol-ws volumio[3071]: info: CorePlayQueue::getTrack 0 May 22 16:55:37 vol-ws volumio[3071]: info: CoreStateMachine::pushState May 22 16:55:37 vol-ws volumio[3071]: info: CorePlayQueue::getTrack 0 May 22 16:55:37 vol-ws volumio[3071]: info: CoreCommandRouter::volumioPushState May 22 16:55:37 vol-ws volumio[3071]: Starting albumart workers May 22 16:55:37 vol-ws volumio[3071]: info: [squeezelite_mc] Server discovery started May 22 16:55:37 vol-ws volumio[3071]: info: [squeezelite_mc] Player finder started May 22 16:55:37 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:37 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:37 vol-ws volumio[3071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:37 vol-ws volumio[3071]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 22 16:55:37 vol-ws volumio[3071]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 16:55:37 vol-ws volumio[3071]: Error: send ENETUNREACH 255.255.255.255:3483 May 22 16:55:37 vol-ws volumio[3071]: at doSend (dgram.js:692:16) May 22 16:55:37 vol-ws volumio[3071]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 22 16:55:37 vol-ws volumio[3071]: at afterDns (dgram.js:638:5) May 22 16:55:37 vol-ws volumio[3071]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 22 16:55:37 vol-ws volumio[3071]: errno: -101, May 22 16:55:37 vol-ws volumio[3071]: code: 'ENETUNREACH', May 22 16:55:37 vol-ws volumio[3071]: syscall: 'send', May 22 16:55:37 vol-ws volumio[3071]: address: '255.255.255.255', May 22 16:55:37 vol-ws volumio[3071]: port: 3483 May 22 16:55:37 vol-ws volumio[3071]: } May 22 16:55:37 vol-ws volumio[3071]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 16:55:37 vol-ws volumio[3071]: Starting albumart workers May 22 16:55:37 vol-ws volumio[3071]: Starting albumart workers May 22 16:55:38 vol-ws kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 May 22 16:55:38 vol-ws sudo[3220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 16:54 May 22 16:55:38 vol-ws sudo[3220]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:39 vol-ws sudo[3220]: pam_unix(sudo:session): session closed for user root May 22 16:55:39 vol-ws volumio-remote-updater[635]: [2025-05-22 16:55:39] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 22 16:55:39 vol-ws volumio-remote-updater[635]: [2025-05-22 16:55:39] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 22 16:55:39 vol-ws systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 22 16:55:39 vol-ws sudo[3164]: pam_unix(sudo:session): session closed for user root May 22 16:55:39 vol-ws sudo[3168]: pam_unix(sudo:session): session closed for user root May 22 16:55:39 vol-ws systemd[1]: volumio.service: Failed with result 'exit-code'. May 22 16:55:39 vol-ws systemd[1]: Started dynamicswap service. May 22 16:55:39 vol-ws systemd[1]: dynamicswap.service: Succeeded. May 22 16:55:39 vol-ws systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 22 16:55:39 vol-ws systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. May 22 16:55:39 vol-ws systemd[1]: Started dynamicswap service. May 22 16:55:39 vol-ws systemd[1]: Stopped Volumio Backend Module. May 22 16:55:39 vol-ws systemd[1]: Started Volumio Backend Module. May 22 16:55:39 vol-ws systemd[1]: dynamicswap.service: Succeeded. May 22 16:55:39 vol-ws mpd[3189]: May 22 16:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 22 16:55:39 vol-ws systemd[1]: Started Music Player Daemon. May 22 16:55:41 vol-ws volumio[3248]: info: ------------------------------------------- May 22 16:55:41 vol-ws volumio[3248]: info: ----- Volumio3 ---- May 22 16:55:41 vol-ws volumio[3248]: info: ------------------------------------------- May 22 16:55:41 vol-ws volumio[3248]: info: ----- System startup ---- May 22 16:55:41 vol-ws volumio[3248]: info: ------------------------------------------- May 22 16:55:42 vol-ws volumio[3248]: info: MYVOLUMIO Environment detected May 22 16:55:42 vol-ws volumio[3248]: info: Plugin folders cleanup May 22 16:55:42 vol-ws volumio[3248]: info: Scanning into folder /volumio/app/plugins/ May 22 16:55:42 vol-ws volumio[3248]: info: Scanning category audio_interface May 22 16:55:42 vol-ws volumio[3248]: info: Scanning category miscellanea May 22 16:55:42 vol-ws volumio[3248]: info: Scanning category music_service May 22 16:55:42 vol-ws volumio[3248]: info: Scanning category plugins.json May 22 16:55:42 vol-ws volumio[3248]: info: Scanning category system_controller May 22 16:55:42 vol-ws volumio[3248]: info: Scanning category user_interface May 22 16:55:42 vol-ws volumio[3248]: info: Scanning into folder /data/plugins/ May 22 16:55:42 vol-ws volumio[3248]: info: Scanning category music_service May 22 16:55:42 vol-ws volumio[3248]: info: Plugin folders cleanup completed May 22 16:55:42 vol-ws volumio[3248]: info: ------------------------------------------- May 22 16:55:42 vol-ws volumio[3248]: info: ----- Core plugins startup ---- May 22 16:55:42 vol-ws volumio[3248]: info: ------------------------------------------- May 22 16:55:42 vol-ws volumio[3248]: info: Loading plugins from folder /volumio/app/plugins/ May 22 16:55:42 vol-ws volumio[3248]: info: Adding plugin upnp to MyMusic Plugins May 22 16:55:42 vol-ws volumio[3248]: info: Adding plugin airplay_emulation to MyMusic Plugins May 22 16:55:42 vol-ws volumio[3248]: info: Adding plugin upnp_browser to MyMusic Plugins May 22 16:55:42 vol-ws volumio[3248]: info: Loading plugins from folder /data/plugins/ May 22 16:55:42 vol-ws volumio[3248]: info: Loading plugin "system"... May 22 16:55:42 vol-ws volumio[3248]: info: Loading plugin "appearance"... May 22 16:55:43 vol-ws volumio[3248]: info: Loading plugin "network"... May 22 16:55:43 vol-ws volumio[3248]: info: Refreshing Cached IP Addresses May 22 16:55:43 vol-ws sudo[3276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 16:55:43 vol-ws sudo[3276]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:43 vol-ws sudo[3276]: pam_unix(sudo:session): session closed for user root May 22 16:55:43 vol-ws sudo[3278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 16:55:43 vol-ws volumio[3248]: info: Loading plugin "services"... May 22 16:55:43 vol-ws sudo[3278]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:43 vol-ws volumio[3248]: info: Loading plugin "alsa_controller"... May 22 16:55:43 vol-ws sudo[3278]: pam_unix(sudo:session): session closed for user root May 22 16:55:43 vol-ws sudo[3281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 22 16:55:43 vol-ws sudo[3281]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:43 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:55:43 vol-ws volumio[3248]: info: Loading plugin "wizard"... May 22 16:55:43 vol-ws volumio[3248]: info: Loading plugin "networkfs"... May 22 16:55:43 vol-ws volumio[3248]: info: Cannot mount NAS audio at system boot, trial number 1 ,retrying in 5 seconds May 22 16:55:43 vol-ws volumio[3248]: info: Starting Udev Watcher for removable devices May 22 16:55:43 vol-ws volumio[3248]: info: Ignoring mount for partition: boot May 22 16:55:43 vol-ws volumio[3248]: info: Ignoring mount for partition: volumio May 22 16:55:43 vol-ws volumio[3248]: info: Ignoring mount for partition: volumio_data May 22 16:55:43 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 16:55:43 vol-ws volumio[3248]: info: Loading plugin "volumio_command_line_client"... May 22 16:55:43 vol-ws volumio[3248]: info: Plugin upnp is not enabled May 22 16:55:43 vol-ws volumio[3248]: info: Loading plugin "my_music"... May 22 16:55:43 vol-ws volumio[3248]: info: Loading plugin "mpd"... May 22 16:55:43 vol-ws volumio[3248]: info: Plugin upnp_browser is not enabled May 22 16:55:43 vol-ws volumio[3248]: info: Loading plugin "alarm-clock"... May 22 16:55:44 vol-ws volumio[3248]: info: Plugin airplay_emulation is not enabled May 22 16:55:44 vol-ws volumio[3248]: info: Loading plugin "last_100"... May 22 16:55:44 vol-ws volumio[3248]: info: Loading plugin "webradio"... May 22 16:55:44 vol-ws volumio[3248]: info: Loading plugin "i2s_dacs"... May 22 16:55:44 vol-ws volumio[3248]: info: Loading plugin "volumiodiscovery"... May 22 16:55:44 vol-ws volumio[3248]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 16:55:44 vol-ws volumio[3248]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:55:44 vol-ws volumio[3248]: *** WARNING *** For more information see May 22 16:55:44 vol-ws node[3248]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 16:55:44 vol-ws volumio[3248]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 16:55:44 vol-ws volumio[3248]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:55:44 vol-ws volumio[3248]: *** WARNING *** For more information see May 22 16:55:44 vol-ws node[3248]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:55:44 vol-ws node[3248]: *** WARNING *** For more information see May 22 16:55:44 vol-ws node[3248]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 16:55:44 vol-ws node[3248]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:55:44 vol-ws node[3248]: *** WARNING *** For more information see May 22 16:55:44 vol-ws volumio[3248]: info: Applying required configuration parameters for plugin volumiodiscovery May 22 16:55:44 vol-ws volumio[3248]: info: Discovery: Started advertising with name: Vol-WS May 22 16:55:44 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 16:55:44 vol-ws volumio[3248]: info: Loading plugin "squeezelite_mc"... May 22 16:55:44 vol-ws volumio-remote-updater[635]: [2025-05-22 16:55:44] [connect] Successful connection May 22 16:55:44 vol-ws sudo[3281]: pam_unix(sudo:session): session closed for user root May 22 16:55:45 vol-ws volumio[3248]: info: Loading plugin "outputs"... May 22 16:55:45 vol-ws volumio[3248]: info: Loading plugin "albumart"... May 22 16:55:45 vol-ws volumio[3248]: info: Plugin example_plugin is not enabled May 22 16:55:45 vol-ws volumio[3248]: info: Loading plugin "inputs"... May 22 16:55:45 vol-ws volumio[3248]: info: Loading plugin "updater_comm"... May 22 16:55:45 vol-ws volumio[3248]: info: Plugin mpdemulation is not enabled May 22 16:55:45 vol-ws volumio[3248]: info: Loading plugin "rest_api"... May 22 16:55:45 vol-ws volumio[3248]: info: Loading plugin "websocket"... May 22 16:55:45 vol-ws volumio[3248]: info: Starting Socket.io Server version 2.3.0 May 22 16:55:45 vol-ws volumio[3248]: info: Loading i18n strings for locale en May 22 16:55:45 vol-ws volumio[3248]: Updating browse sources language May 22 16:55:45 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:55:45 vol-ws volumio[3248]: Forking 3 albumart workers May 22 16:55:45 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 16:55:45 vol-ws volumio[3248]: info: CoreCommandRouter::initPlayerControls May 22 16:55:45 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:45 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:45 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:45 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:45 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:45 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:45 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:45 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:45 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 16:55:45 vol-ws volumio[3248]: Express server listening on port 3000 May 22 16:55:45 vol-ws volumio[3248]: [Metrics] WebUI: 4s 768.88ms May 22 16:55:45 vol-ws volumio[3248]: info: CoreStateMachine::resetVolumioState May 22 16:55:45 vol-ws volumio[3248]: info: CoreStateMachine::getcurrentVolume May 22 16:55:45 vol-ws volumio[3248]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:55:45 vol-ws volumio[3248]: info: Cannot read play queue from file May 22 16:55:45 vol-ws volumio-remote-updater[635]: [2025-05-22 16:55:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747925744 101 May 22 16:55:45 vol-ws volumio[3248]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 May 22 16:55:45 vol-ws volumio[3248]: info: CoreStateMachine::pushState May 22 16:55:45 vol-ws volumio[3248]: info: CorePlayQueue::getTrack 0 May 22 16:55:45 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 16:55:45 vol-ws volumio[3248]: info: CoreCommandRouter::volumioPushState May 22 16:55:45 vol-ws volumio[3248]: info: CoreStateMachine::updateTrackBlock May 22 16:55:45 vol-ws volumio[3248]: info: CorePlayQueue::getTrackBlock May 22 16:55:45 vol-ws volumio[3248]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:55:45 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:55:45 vol-ws volumio[3248]: info: Executing start script for DAC IQaudIO DAC Plus May 22 16:55:45 vol-ws volumio[3248]: info: Setting Device type: Raspberry PI May 22 16:55:45 vol-ws volumio[3248]: info: CoreStateMachine::setRepeat null single undefined May 22 16:55:45 vol-ws volumio[3248]: info: CoreStateMachine::pushState May 22 16:55:45 vol-ws volumio[3248]: info: CorePlayQueue::getTrack 0 May 22 16:55:45 vol-ws volumio[3248]: info: CoreCommandRouter::volumioPushState May 22 16:55:45 vol-ws volumio[3248]: info: CoreStateMachine::setRandom null May 22 16:55:45 vol-ws volumio[3248]: info: CoreStateMachine::pushState May 22 16:55:45 vol-ws volumio[3248]: info: CorePlayQueue::getTrack 0 May 22 16:55:45 vol-ws volumio[3248]: info: CoreCommandRouter::volumioPushState May 22 16:55:45 vol-ws volumio[3248]: info: CoreStateMachine::pushState May 22 16:55:45 vol-ws volumio[3248]: info: CorePlayQueue::getTrack 0 May 22 16:55:45 vol-ws volumio[3248]: info: CoreCommandRouter::volumioPushState May 22 16:55:45 vol-ws volumio[3248]: info: Discovery: adding 544166b9-d1dd-4d34-96ac-979983e6a685 May 22 16:55:46 vol-ws volumio[3248]: info: Discovery: Found device Vol-WS May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::volumioGetState May 22 16:55:46 vol-ws volumio[3248]: info: CorePlayQueue::getTrack 0 May 22 16:55:46 vol-ws volumio[3248]: info: DAC script executed May 22 16:55:46 vol-ws volumio[3248]: info: Completed loading Core Plugins May 22 16:55:46 vol-ws volumio[3248]: info: Preparing to generate the ALSA configuration file May 22 16:55:46 vol-ws volumio[3248]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 22 16:55:46 vol-ws volumio[3248]: info: Reading ALSA contributions from plugins. May 22 16:55:46 vol-ws volumio[3248]: info: Asound.conf file unchanged, so no further update is needed May 22 16:55:46 vol-ws volumio[3248]: info: Output device has changed, restarting MPD May 22 16:55:46 vol-ws volumio[3248]: info: ___________ START PLUGINS ___________ May 22 16:55:46 vol-ws volumio[3248]: info: ControllerMpd::onStart: Initializing MPD May 22 16:55:46 vol-ws volumio[3248]: info: Creating MPD Configuration file May 22 16:55:46 vol-ws sudo[3342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 16:55:46 vol-ws sudo[3342]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:46 vol-ws sudo[3343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 16:55:46 vol-ws sudo[3346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 16:55:46 vol-ws sudo[3342]: pam_unix(sudo:session): session closed for user root May 22 16:55:46 vol-ws sudo[3346]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:46 vol-ws sudo[3343]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:46 vol-ws sudo[3346]: pam_unix(sudo:session): session closed for user root May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 16:55:46 vol-ws volumio[3248]: info: [1747925746197] CoreMusicLibrary::Adding element Last_100 May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 16:55:46 vol-ws volumio[3248]: info: [1747925746205] CoreMusicLibrary::Adding element Webradio May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:55:46 vol-ws volumio[3248]: info: Initializing BBC Radios May 22 16:55:46 vol-ws systemd[1]: Stopping Music Player Daemon... May 22 16:55:46 vol-ws sudo[3350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 16:55:46 vol-ws sudo[3350]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:46 vol-ws systemd[1]: mpd.service: Succeeded. May 22 16:55:46 vol-ws systemd[1]: Stopped Music Player Daemon. May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:55:46 vol-ws systemd[1]: Starting Music Player Daemon... May 22 16:55:46 vol-ws systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 22 16:55:46 vol-ws systemd[1]: mpd.service: Succeeded. May 22 16:55:46 vol-ws systemd[1]: Stopped Music Player Daemon. May 22 16:55:46 vol-ws systemd[1]: Starting Music Player Daemon... May 22 16:55:46 vol-ws volumio[3248]: info: [squeezelite_mc] Starting proxy server... May 22 16:55:46 vol-ws volumio[3248]: info: Volumio Calling Home May 22 16:55:46 vol-ws sudo[3363]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 16:55:46 vol-ws sudo[3363]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:46 vol-ws sudo[3363]: pam_unix(sudo:session): session closed for user root May 22 16:55:46 vol-ws volumio[3248]: info: [squeezelite_mc] Proxy server started on port 44683 May 22 16:55:46 vol-ws volumio[3248]: Starting albumart workers May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:55:46 vol-ws volumio[3248]: info: MPD Permissions set May 22 16:55:46 vol-ws volumio[3248]: info: MPD Permissions set May 22 16:55:46 vol-ws volumio[3248]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:55:46 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:46 vol-ws volumio[3248]: Starting albumart workers May 22 16:55:47 vol-ws volumio[3248]: info: CoreStateMachine::pushState May 22 16:55:47 vol-ws volumio[3248]: info: CorePlayQueue::getTrack 0 May 22 16:55:47 vol-ws volumio[3248]: info: CoreCommandRouter::volumioPushState May 22 16:55:47 vol-ws volumio[3248]: info: [squeezelite_mc] Server discovery started May 22 16:55:47 vol-ws volumio[3248]: info: [squeezelite_mc] Player finder started May 22 16:55:47 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:47 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:47 vol-ws volumio[3248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:47 vol-ws volumio[3248]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 22 16:55:47 vol-ws volumio[3248]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 16:55:47 vol-ws volumio[3248]: Starting albumart workers May 22 16:55:47 vol-ws volumio[3248]: Error: send ENETUNREACH 255.255.255.255:3483 May 22 16:55:47 vol-ws volumio[3248]: at doSend (dgram.js:692:16) May 22 16:55:47 vol-ws volumio[3248]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 22 16:55:47 vol-ws volumio[3248]: at afterDns (dgram.js:638:5) May 22 16:55:47 vol-ws volumio[3248]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 22 16:55:47 vol-ws volumio[3248]: errno: -101, May 22 16:55:47 vol-ws volumio[3248]: code: 'ENETUNREACH', May 22 16:55:47 vol-ws volumio[3248]: syscall: 'send', May 22 16:55:47 vol-ws volumio[3248]: address: '255.255.255.255', May 22 16:55:47 vol-ws volumio[3248]: port: 3483 May 22 16:55:47 vol-ws volumio[3248]: } May 22 16:55:47 vol-ws volumio[3248]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 16:55:47 vol-ws kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 May 22 16:55:48 vol-ws sudo[3418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 16:54 May 22 16:55:48 vol-ws sudo[3418]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:48 vol-ws sudo[3418]: pam_unix(sudo:session): session closed for user root May 22 16:55:48 vol-ws volumio-remote-updater[635]: [2025-05-22 16:55:48] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 22 16:55:48 vol-ws volumio-remote-updater[635]: [2025-05-22 16:55:48] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 22 16:55:48 vol-ws systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 22 16:55:48 vol-ws sudo[3350]: pam_unix(sudo:session): session closed for user root May 22 16:55:48 vol-ws sudo[3343]: pam_unix(sudo:session): session closed for user root May 22 16:55:48 vol-ws systemd[1]: volumio.service: Failed with result 'exit-code'. May 22 16:55:48 vol-ws systemd[1]: Started dynamicswap service. May 22 16:55:48 vol-ws systemd[1]: dynamicswap.service: Succeeded. May 22 16:55:48 vol-ws mpd[3367]: May 22 16:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 22 16:55:48 vol-ws systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 22 16:55:48 vol-ws systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9. May 22 16:55:48 vol-ws systemd[1]: Started dynamicswap service. May 22 16:55:48 vol-ws systemd[1]: Stopped Volumio Backend Module. May 22 16:55:48 vol-ws systemd[1]: Started Volumio Backend Module. May 22 16:55:48 vol-ws systemd[1]: Started Music Player Daemon. May 22 16:55:48 vol-ws systemd[1]: dynamicswap.service: Succeeded. May 22 16:55:50 vol-ws volumio[3432]: info: ------------------------------------------- May 22 16:55:50 vol-ws volumio[3432]: info: ----- Volumio3 ---- May 22 16:55:50 vol-ws volumio[3432]: info: ------------------------------------------- May 22 16:55:50 vol-ws volumio[3432]: info: ----- System startup ---- May 22 16:55:50 vol-ws volumio[3432]: info: ------------------------------------------- May 22 16:55:51 vol-ws volumio[3432]: info: MYVOLUMIO Environment detected May 22 16:55:51 vol-ws volumio[3432]: info: Plugin folders cleanup May 22 16:55:51 vol-ws volumio[3432]: info: Scanning into folder /volumio/app/plugins/ May 22 16:55:51 vol-ws volumio[3432]: info: Scanning category audio_interface May 22 16:55:51 vol-ws volumio[3432]: info: Scanning category miscellanea May 22 16:55:51 vol-ws volumio[3432]: info: Scanning category music_service May 22 16:55:51 vol-ws volumio[3432]: info: Scanning category plugins.json May 22 16:55:51 vol-ws volumio[3432]: info: Scanning category system_controller May 22 16:55:51 vol-ws volumio[3432]: info: Scanning category user_interface May 22 16:55:51 vol-ws volumio[3432]: info: Scanning into folder /data/plugins/ May 22 16:55:51 vol-ws volumio[3432]: info: Scanning category music_service May 22 16:55:51 vol-ws volumio[3432]: info: Plugin folders cleanup completed May 22 16:55:51 vol-ws volumio[3432]: info: ------------------------------------------- May 22 16:55:51 vol-ws volumio[3432]: info: ----- Core plugins startup ---- May 22 16:55:51 vol-ws volumio[3432]: info: ------------------------------------------- May 22 16:55:51 vol-ws volumio[3432]: info: Loading plugins from folder /volumio/app/plugins/ May 22 16:55:51 vol-ws volumio[3432]: info: Adding plugin upnp to MyMusic Plugins May 22 16:55:51 vol-ws volumio[3432]: info: Adding plugin airplay_emulation to MyMusic Plugins May 22 16:55:51 vol-ws volumio[3432]: info: Adding plugin upnp_browser to MyMusic Plugins May 22 16:55:51 vol-ws volumio[3432]: info: Loading plugins from folder /data/plugins/ May 22 16:55:51 vol-ws volumio[3432]: info: Loading plugin "system"... May 22 16:55:51 vol-ws volumio[3432]: info: Loading plugin "appearance"... May 22 16:55:52 vol-ws volumio[3432]: info: Loading plugin "network"... May 22 16:55:52 vol-ws volumio[3432]: info: Refreshing Cached IP Addresses May 22 16:55:52 vol-ws sudo[3460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 16:55:52 vol-ws sudo[3460]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:52 vol-ws sudo[3460]: pam_unix(sudo:session): session closed for user root May 22 16:55:52 vol-ws sudo[3462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 16:55:52 vol-ws volumio[3432]: info: Loading plugin "services"... May 22 16:55:52 vol-ws sudo[3462]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:52 vol-ws volumio[3432]: info: Loading plugin "alsa_controller"... May 22 16:55:52 vol-ws sudo[3462]: pam_unix(sudo:session): session closed for user root May 22 16:55:52 vol-ws sudo[3465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 22 16:55:52 vol-ws sudo[3465]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:52 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:55:52 vol-ws volumio[3432]: info: Loading plugin "wizard"... May 22 16:55:52 vol-ws volumio[3432]: info: Loading plugin "networkfs"... May 22 16:55:52 vol-ws volumio[3432]: info: Cannot mount NAS audio at system boot, trial number 1 ,retrying in 5 seconds May 22 16:55:52 vol-ws volumio[3432]: info: Starting Udev Watcher for removable devices May 22 16:55:52 vol-ws volumio[3432]: info: Ignoring mount for partition: boot May 22 16:55:52 vol-ws volumio[3432]: info: Ignoring mount for partition: volumio May 22 16:55:52 vol-ws volumio[3432]: info: Ignoring mount for partition: volumio_data May 22 16:55:52 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 16:55:52 vol-ws volumio[3432]: info: Loading plugin "volumio_command_line_client"... May 22 16:55:52 vol-ws volumio[3432]: info: Plugin upnp is not enabled May 22 16:55:52 vol-ws volumio[3432]: info: Loading plugin "my_music"... May 22 16:55:52 vol-ws volumio[3432]: info: Loading plugin "mpd"... May 22 16:55:53 vol-ws volumio[3432]: info: Plugin upnp_browser is not enabled May 22 16:55:53 vol-ws volumio[3432]: info: Loading plugin "alarm-clock"... May 22 16:55:53 vol-ws volumio[3432]: info: Plugin airplay_emulation is not enabled May 22 16:55:53 vol-ws volumio[3432]: info: Loading plugin "last_100"... May 22 16:55:53 vol-ws volumio[3432]: info: Loading plugin "webradio"... May 22 16:55:53 vol-ws volumio-remote-updater[635]: [2025-05-22 16:55:53] [connect] Successful connection May 22 16:55:53 vol-ws volumio[3432]: info: Loading plugin "i2s_dacs"... May 22 16:55:53 vol-ws volumio[3432]: info: Loading plugin "volumiodiscovery"... May 22 16:55:53 vol-ws volumio[3432]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 16:55:53 vol-ws volumio[3432]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:55:53 vol-ws volumio[3432]: *** WARNING *** For more information see May 22 16:55:53 vol-ws node[3432]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 16:55:53 vol-ws volumio[3432]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 16:55:53 vol-ws volumio[3432]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:55:53 vol-ws volumio[3432]: *** WARNING *** For more information see May 22 16:55:53 vol-ws node[3432]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:55:53 vol-ws node[3432]: *** WARNING *** For more information see May 22 16:55:53 vol-ws node[3432]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 16:55:53 vol-ws node[3432]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:55:53 vol-ws node[3432]: *** WARNING *** For more information see May 22 16:55:53 vol-ws volumio[3432]: info: Applying required configuration parameters for plugin volumiodiscovery May 22 16:55:53 vol-ws volumio[3432]: info: Discovery: Started advertising with name: Vol-WS May 22 16:55:53 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 16:55:53 vol-ws volumio[3432]: info: Loading plugin "squeezelite_mc"... May 22 16:55:53 vol-ws sudo[3465]: pam_unix(sudo:session): session closed for user root May 22 16:55:54 vol-ws volumio[3432]: info: Loading plugin "outputs"... May 22 16:55:54 vol-ws volumio[3432]: info: Loading plugin "albumart"... May 22 16:55:54 vol-ws volumio[3432]: info: Plugin example_plugin is not enabled May 22 16:55:54 vol-ws volumio[3432]: info: Loading plugin "inputs"... May 22 16:55:54 vol-ws volumio[3432]: info: Loading plugin "updater_comm"... May 22 16:55:54 vol-ws volumio[3432]: info: Plugin mpdemulation is not enabled May 22 16:55:54 vol-ws volumio[3432]: info: Loading plugin "rest_api"... May 22 16:55:54 vol-ws volumio[3432]: info: Loading plugin "websocket"... May 22 16:55:54 vol-ws volumio[3432]: info: Starting Socket.io Server version 2.3.0 May 22 16:55:54 vol-ws volumio[3432]: info: Loading i18n strings for locale en May 22 16:55:54 vol-ws volumio[3432]: Updating browse sources language May 22 16:55:54 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:55:54 vol-ws volumio[3432]: Forking 3 albumart workers May 22 16:55:54 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 16:55:54 vol-ws volumio[3432]: info: CoreCommandRouter::initPlayerControls May 22 16:55:54 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:54 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:54 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:54 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:54 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:54 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:54 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:54 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:54 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 16:55:54 vol-ws volumio[3432]: Express server listening on port 3000 May 22 16:55:54 vol-ws volumio[3432]: [Metrics] WebUI: 4s 773.45ms May 22 16:55:54 vol-ws volumio[3432]: info: CoreStateMachine::resetVolumioState May 22 16:55:54 vol-ws volumio[3432]: info: CoreStateMachine::getcurrentVolume May 22 16:55:54 vol-ws volumio[3432]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:55:54 vol-ws volumio[3432]: info: Cannot read play queue from file May 22 16:55:54 vol-ws volumio-remote-updater[635]: [2025-05-22 16:55:54] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747925753 101 May 22 16:55:55 vol-ws volumio[3432]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 May 22 16:55:55 vol-ws volumio[3432]: info: CoreStateMachine::pushState May 22 16:55:55 vol-ws volumio[3432]: info: CorePlayQueue::getTrack 0 May 22 16:55:55 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 16:55:55 vol-ws volumio[3432]: info: CoreCommandRouter::volumioPushState May 22 16:55:55 vol-ws volumio[3432]: info: CoreStateMachine::updateTrackBlock May 22 16:55:55 vol-ws volumio[3432]: info: CorePlayQueue::getTrackBlock May 22 16:55:55 vol-ws volumio[3432]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:55:55 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:55:55 vol-ws volumio[3432]: info: Executing start script for DAC IQaudIO DAC Plus May 22 16:55:55 vol-ws volumio[3432]: info: Setting Device type: Raspberry PI May 22 16:55:55 vol-ws volumio[3432]: info: CoreStateMachine::setRepeat null single undefined May 22 16:55:55 vol-ws volumio[3432]: info: CoreStateMachine::pushState May 22 16:55:55 vol-ws volumio[3432]: info: CorePlayQueue::getTrack 0 May 22 16:55:55 vol-ws volumio[3432]: info: CoreCommandRouter::volumioPushState May 22 16:55:55 vol-ws volumio[3432]: info: CoreStateMachine::setRandom null May 22 16:55:55 vol-ws volumio[3432]: info: CoreStateMachine::pushState May 22 16:55:55 vol-ws volumio[3432]: info: CorePlayQueue::getTrack 0 May 22 16:55:55 vol-ws volumio[3432]: info: CoreCommandRouter::volumioPushState May 22 16:55:55 vol-ws volumio[3432]: info: CoreStateMachine::pushState May 22 16:55:55 vol-ws volumio[3432]: info: CorePlayQueue::getTrack 0 May 22 16:55:55 vol-ws volumio[3432]: info: CoreCommandRouter::volumioPushState May 22 16:55:55 vol-ws volumio[3432]: info: Discovery: adding 544166b9-d1dd-4d34-96ac-979983e6a685 May 22 16:55:55 vol-ws volumio[3432]: info: Discovery: Found device Vol-WS May 22 16:55:55 vol-ws volumio[3432]: info: CoreCommandRouter::volumioGetState May 22 16:55:55 vol-ws volumio[3432]: info: CorePlayQueue::getTrack 0 May 22 16:55:55 vol-ws volumio[3432]: info: DAC script executed May 22 16:55:55 vol-ws volumio[3432]: info: Completed loading Core Plugins May 22 16:55:55 vol-ws volumio[3432]: info: Preparing to generate the ALSA configuration file May 22 16:55:55 vol-ws volumio[3432]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 22 16:55:55 vol-ws volumio[3432]: info: Reading ALSA contributions from plugins. May 22 16:55:55 vol-ws volumio[3432]: info: Asound.conf file unchanged, so no further update is needed May 22 16:55:55 vol-ws volumio[3432]: info: Output device has changed, restarting MPD May 22 16:55:55 vol-ws volumio[3432]: info: ___________ START PLUGINS ___________ May 22 16:55:55 vol-ws volumio[3432]: info: ControllerMpd::onStart: Initializing MPD May 22 16:55:55 vol-ws volumio[3432]: info: Creating MPD Configuration file May 22 16:55:55 vol-ws sudo[3527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 16:55:55 vol-ws sudo[3527]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:55 vol-ws sudo[3526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 16:55:55 vol-ws sudo[3526]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:55 vol-ws sudo[3526]: pam_unix(sudo:session): session closed for user root May 22 16:55:55 vol-ws volumio[3432]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 16:55:55 vol-ws volumio[3432]: info: [1747925755450] CoreMusicLibrary::Adding element Last_100 May 22 16:55:55 vol-ws sudo[3530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 16:55:55 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:55:55 vol-ws sudo[3530]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:55 vol-ws volumio[3432]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 16:55:55 vol-ws systemd[1]: Stopping Music Player Daemon... May 22 16:55:55 vol-ws volumio[3432]: info: [1747925755473] CoreMusicLibrary::Adding element Webradio May 22 16:55:55 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:55:55 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:55:55 vol-ws sudo[3530]: pam_unix(sudo:session): session closed for user root May 22 16:55:55 vol-ws volumio[3432]: info: Initializing BBC Radios May 22 16:55:55 vol-ws sudo[3533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 16:55:55 vol-ws sudo[3533]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:55 vol-ws systemd[1]: mpd.service: Succeeded. May 22 16:55:55 vol-ws systemd[1]: Stopped Music Player Daemon. May 22 16:55:55 vol-ws systemd[1]: Starting Music Player Daemon... May 22 16:55:55 vol-ws systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 22 16:55:55 vol-ws systemd[1]: mpd.service: Succeeded. May 22 16:55:55 vol-ws systemd[1]: Stopped Music Player Daemon. May 22 16:55:55 vol-ws systemd[1]: Starting Music Player Daemon... May 22 16:55:55 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:55:55 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:55:55 vol-ws volumio[3432]: info: [squeezelite_mc] Starting proxy server... May 22 16:55:55 vol-ws volumio[3432]: info: Volumio Calling Home May 22 16:55:55 vol-ws sudo[3548]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 16:55:55 vol-ws sudo[3548]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:55 vol-ws sudo[3548]: pam_unix(sudo:session): session closed for user root May 22 16:55:55 vol-ws volumio[3432]: info: [squeezelite_mc] Proxy server started on port 45655 May 22 16:55:55 vol-ws volumio[3432]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:55:56 vol-ws volumio[3432]: Starting albumart workers May 22 16:55:56 vol-ws volumio[3432]: Starting albumart workers May 22 16:55:56 vol-ws volumio[3432]: info: MPD Permissions set May 22 16:55:56 vol-ws volumio[3432]: info: MPD Permissions set May 22 16:55:56 vol-ws volumio[3432]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: Starting albumart workers May 22 16:55:56 vol-ws volumio[3432]: info: CoreStateMachine::pushState May 22 16:55:56 vol-ws volumio[3432]: info: CorePlayQueue::getTrack 0 May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::volumioPushState May 22 16:55:56 vol-ws volumio[3432]: info: [squeezelite_mc] Server discovery started May 22 16:55:56 vol-ws volumio[3432]: info: [squeezelite_mc] Player finder started May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:55:56 vol-ws volumio[3432]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 22 16:55:56 vol-ws volumio[3432]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 16:55:56 vol-ws volumio[3432]: Error: send ENETUNREACH 255.255.255.255:3483 May 22 16:55:56 vol-ws volumio[3432]: at doSend (dgram.js:692:16) May 22 16:55:56 vol-ws volumio[3432]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 22 16:55:56 vol-ws volumio[3432]: at afterDns (dgram.js:638:5) May 22 16:55:56 vol-ws volumio[3432]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 22 16:55:56 vol-ws volumio[3432]: errno: -101, May 22 16:55:56 vol-ws volumio[3432]: code: 'ENETUNREACH', May 22 16:55:56 vol-ws volumio[3432]: syscall: 'send', May 22 16:55:56 vol-ws volumio[3432]: address: '255.255.255.255', May 22 16:55:56 vol-ws volumio[3432]: port: 3483 May 22 16:55:56 vol-ws volumio[3432]: } May 22 16:55:56 vol-ws volumio[3432]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 16:55:56 vol-ws kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 May 22 16:55:57 vol-ws sudo[3606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 16:54 May 22 16:55:57 vol-ws sudo[3606]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:55:57 vol-ws sudo[3606]: pam_unix(sudo:session): session closed for user root May 22 16:55:57 vol-ws volumio-remote-updater[635]: [2025-05-22 16:55:57] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 22 16:55:57 vol-ws volumio-remote-updater[635]: [2025-05-22 16:55:57] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 22 16:55:57 vol-ws systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 22 16:55:57 vol-ws sudo[3527]: pam_unix(sudo:session): session closed for user root May 22 16:55:57 vol-ws sudo[3533]: pam_unix(sudo:session): session closed for user root May 22 16:55:57 vol-ws systemd[1]: volumio.service: Failed with result 'exit-code'. May 22 16:55:57 vol-ws systemd[1]: Started dynamicswap service. May 22 16:55:57 vol-ws systemd[1]: dynamicswap.service: Succeeded. May 22 16:55:57 vol-ws mpd[3555]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 22 16:55:57 vol-ws mpd[3555]: output: No 'audio_output' defined in config file May 22 16:55:57 vol-ws mpd[3555]: output: Successfully detected a sndio audio device May 22 16:55:57 vol-ws mpd[3555]: zeroconf: No global port, disabling zeroconf May 22 16:55:57 vol-ws systemd[1]: Started Music Player Daemon. May 22 16:55:57 vol-ws systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 22 16:55:57 vol-ws systemd[1]: volumio.service: Scheduled restart job, restart counter is at 10. May 22 16:55:57 vol-ws systemd[1]: Started dynamicswap service. May 22 16:55:57 vol-ws systemd[1]: Stopped Volumio Backend Module. May 22 16:55:57 vol-ws systemd[1]: Started Volumio Backend Module. May 22 16:55:57 vol-ws systemd[1]: dynamicswap.service: Succeeded. May 22 16:55:58 vol-ws nmbd[763]: [2025/05/22 16:55:58.905109, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 22 16:55:58 vol-ws nmbd[763]: Packet send failed to 10.10.10.255(137) ERRNO=Network is unreachable May 22 16:55:58 vol-ws nmbd[763]: [2025/05/22 16:55:58.905410, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) May 22 16:55:58 vol-ws nmbd[763]: send_netbios_packet: send_packet() to IP 10.10.10.255 port 137 failed May 22 16:55:58 vol-ws nmbd[763]: [2025/05/22 16:55:58.905500, 0] ../source3/nmbd/nmbd_namequery.c:245(query_name) May 22 16:55:58 vol-ws nmbd[763]: query_name: Failed to send packet trying to query name WORKGROUP<1d> May 22 16:55:59 vol-ws volumio[3623]: info: ------------------------------------------- May 22 16:55:59 vol-ws volumio[3623]: info: ----- Volumio3 ---- May 22 16:55:59 vol-ws volumio[3623]: info: ------------------------------------------- May 22 16:55:59 vol-ws volumio[3623]: info: ----- System startup ---- May 22 16:55:59 vol-ws volumio[3623]: info: ------------------------------------------- May 22 16:56:00 vol-ws volumio[3623]: info: MYVOLUMIO Environment detected May 22 16:56:00 vol-ws volumio[3623]: info: Plugin folders cleanup May 22 16:56:00 vol-ws volumio[3623]: info: Scanning into folder /volumio/app/plugins/ May 22 16:56:00 vol-ws volumio[3623]: info: Scanning category audio_interface May 22 16:56:00 vol-ws volumio[3623]: info: Scanning category miscellanea May 22 16:56:00 vol-ws volumio[3623]: info: Scanning category music_service May 22 16:56:00 vol-ws volumio[3623]: info: Scanning category plugins.json May 22 16:56:00 vol-ws volumio[3623]: info: Scanning category system_controller May 22 16:56:00 vol-ws volumio[3623]: info: Scanning category user_interface May 22 16:56:00 vol-ws volumio[3623]: info: Scanning into folder /data/plugins/ May 22 16:56:00 vol-ws volumio[3623]: info: Scanning category music_service May 22 16:56:00 vol-ws volumio[3623]: info: Plugin folders cleanup completed May 22 16:56:00 vol-ws volumio[3623]: info: ------------------------------------------- May 22 16:56:00 vol-ws volumio[3623]: info: ----- Core plugins startup ---- May 22 16:56:00 vol-ws volumio[3623]: info: ------------------------------------------- May 22 16:56:00 vol-ws volumio[3623]: info: Loading plugins from folder /volumio/app/plugins/ May 22 16:56:00 vol-ws volumio[3623]: info: Adding plugin upnp to MyMusic Plugins May 22 16:56:00 vol-ws volumio[3623]: info: Adding plugin airplay_emulation to MyMusic Plugins May 22 16:56:00 vol-ws volumio[3623]: info: Adding plugin upnp_browser to MyMusic Plugins May 22 16:56:00 vol-ws volumio[3623]: info: Loading plugins from folder /data/plugins/ May 22 16:56:00 vol-ws volumio[3623]: info: Loading plugin "system"... May 22 16:56:00 vol-ws volumio[3623]: info: Loading plugin "appearance"... May 22 16:56:00 vol-ws nmbd[763]: [2025/05/22 16:56:00.908864, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 22 16:56:00 vol-ws nmbd[763]: Packet send failed to 10.10.10.255(138) ERRNO=Network is unreachable May 22 16:56:01 vol-ws volumio[3623]: info: Loading plugin "network"... May 22 16:56:01 vol-ws volumio[3623]: info: Refreshing Cached IP Addresses May 22 16:56:01 vol-ws sudo[3650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 16:56:01 vol-ws sudo[3650]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:01 vol-ws sudo[3650]: pam_unix(sudo:session): session closed for user root May 22 16:56:01 vol-ws volumio[3623]: info: Loading plugin "services"... May 22 16:56:01 vol-ws sudo[3652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 16:56:01 vol-ws sudo[3652]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:01 vol-ws volumio[3623]: info: Loading plugin "alsa_controller"... May 22 16:56:01 vol-ws sudo[3652]: pam_unix(sudo:session): session closed for user root May 22 16:56:01 vol-ws sudo[3655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 22 16:56:01 vol-ws sudo[3655]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:01 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:56:01 vol-ws volumio[3623]: info: Loading plugin "wizard"... May 22 16:56:01 vol-ws volumio[3623]: info: Loading plugin "networkfs"... May 22 16:56:01 vol-ws volumio[3623]: info: Cannot mount NAS audio at system boot, trial number 1 ,retrying in 5 seconds May 22 16:56:01 vol-ws volumio[3623]: info: Starting Udev Watcher for removable devices May 22 16:56:01 vol-ws volumio[3623]: info: Ignoring mount for partition: boot May 22 16:56:01 vol-ws volumio[3623]: info: Ignoring mount for partition: volumio May 22 16:56:01 vol-ws volumio[3623]: info: Ignoring mount for partition: volumio_data May 22 16:56:01 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 16:56:01 vol-ws volumio[3623]: info: Loading plugin "volumio_command_line_client"... May 22 16:56:01 vol-ws volumio[3623]: info: Plugin upnp is not enabled May 22 16:56:01 vol-ws volumio[3623]: info: Loading plugin "my_music"... May 22 16:56:01 vol-ws volumio[3623]: info: Loading plugin "mpd"... May 22 16:56:02 vol-ws volumio[3623]: info: Plugin upnp_browser is not enabled May 22 16:56:02 vol-ws volumio[3623]: info: Loading plugin "alarm-clock"... May 22 16:56:02 vol-ws volumio[3623]: info: Plugin airplay_emulation is not enabled May 22 16:56:02 vol-ws volumio[3623]: info: Loading plugin "last_100"... May 22 16:56:02 vol-ws volumio[3623]: info: Loading plugin "webradio"... May 22 16:56:02 vol-ws volumio[3623]: info: Loading plugin "i2s_dacs"... May 22 16:56:02 vol-ws volumio[3623]: info: Loading plugin "volumiodiscovery"... May 22 16:56:02 vol-ws volumio-remote-updater[635]: [2025-05-22 16:56:02] [connect] Successful connection May 22 16:56:02 vol-ws volumio[3623]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 16:56:02 vol-ws volumio[3623]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:56:02 vol-ws volumio[3623]: *** WARNING *** For more information see May 22 16:56:02 vol-ws node[3623]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 16:56:02 vol-ws volumio[3623]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 16:56:02 vol-ws volumio[3623]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:56:02 vol-ws volumio[3623]: *** WARNING *** For more information see May 22 16:56:02 vol-ws node[3623]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:56:02 vol-ws node[3623]: *** WARNING *** For more information see May 22 16:56:02 vol-ws node[3623]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 16:56:02 vol-ws node[3623]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:56:02 vol-ws node[3623]: *** WARNING *** For more information see May 22 16:56:02 vol-ws volumio[3623]: info: Applying required configuration parameters for plugin volumiodiscovery May 22 16:56:02 vol-ws volumio[3623]: info: Discovery: Started advertising with name: Vol-WS May 22 16:56:02 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 16:56:02 vol-ws volumio[3623]: info: Loading plugin "squeezelite_mc"... May 22 16:56:02 vol-ws sudo[3655]: pam_unix(sudo:session): session closed for user root May 22 16:56:03 vol-ws volumio[3623]: info: Loading plugin "outputs"... May 22 16:56:03 vol-ws volumio[3623]: info: Loading plugin "albumart"... May 22 16:56:03 vol-ws volumio[3623]: info: Plugin example_plugin is not enabled May 22 16:56:03 vol-ws volumio[3623]: info: Loading plugin "inputs"... May 22 16:56:03 vol-ws volumio[3623]: info: Loading plugin "updater_comm"... May 22 16:56:03 vol-ws volumio[3623]: info: Plugin mpdemulation is not enabled May 22 16:56:03 vol-ws volumio[3623]: info: Loading plugin "rest_api"... May 22 16:56:03 vol-ws volumio[3623]: info: Loading plugin "websocket"... May 22 16:56:03 vol-ws volumio[3623]: info: Starting Socket.io Server version 2.3.0 May 22 16:56:03 vol-ws volumio[3623]: info: Loading i18n strings for locale en May 22 16:56:03 vol-ws volumio[3623]: Updating browse sources language May 22 16:56:03 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:56:03 vol-ws volumio[3623]: Forking 3 albumart workers May 22 16:56:03 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 16:56:03 vol-ws volumio[3623]: info: CoreCommandRouter::initPlayerControls May 22 16:56:03 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:03 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:03 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:03 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:03 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:03 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:03 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:03 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:03 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 16:56:03 vol-ws volumio[3623]: Express server listening on port 3000 May 22 16:56:03 vol-ws volumio[3623]: [Metrics] WebUI: 4s 750.64ms May 22 16:56:04 vol-ws volumio[3623]: info: CoreStateMachine::resetVolumioState May 22 16:56:04 vol-ws volumio[3623]: info: CoreStateMachine::getcurrentVolume May 22 16:56:04 vol-ws volumio[3623]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:56:04 vol-ws volumio[3623]: info: Cannot read play queue from file May 22 16:56:04 vol-ws volumio-remote-updater[635]: [2025-05-22 16:56:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747925762 101 May 22 16:56:04 vol-ws volumio[3623]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 May 22 16:56:04 vol-ws volumio[3623]: info: CoreStateMachine::pushState May 22 16:56:04 vol-ws volumio[3623]: info: CorePlayQueue::getTrack 0 May 22 16:56:04 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 16:56:04 vol-ws volumio[3623]: info: CoreCommandRouter::volumioPushState May 22 16:56:04 vol-ws volumio[3623]: info: CoreStateMachine::updateTrackBlock May 22 16:56:04 vol-ws volumio[3623]: info: CorePlayQueue::getTrackBlock May 22 16:56:04 vol-ws volumio[3623]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:56:04 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:56:04 vol-ws volumio[3623]: info: Executing start script for DAC IQaudIO DAC Plus May 22 16:56:04 vol-ws volumio[3623]: info: CoreStateMachine::setRepeat null single undefined May 22 16:56:04 vol-ws volumio[3623]: info: CoreStateMachine::pushState May 22 16:56:04 vol-ws volumio[3623]: info: CorePlayQueue::getTrack 0 May 22 16:56:04 vol-ws volumio[3623]: info: CoreCommandRouter::volumioPushState May 22 16:56:04 vol-ws volumio[3623]: info: CoreStateMachine::setRandom null May 22 16:56:04 vol-ws volumio[3623]: info: CoreStateMachine::pushState May 22 16:56:04 vol-ws volumio[3623]: info: CorePlayQueue::getTrack 0 May 22 16:56:04 vol-ws volumio[3623]: info: CoreCommandRouter::volumioPushState May 22 16:56:04 vol-ws volumio[3623]: info: Setting Device type: Raspberry PI May 22 16:56:04 vol-ws volumio[3623]: info: CoreStateMachine::pushState May 22 16:56:04 vol-ws volumio[3623]: info: CorePlayQueue::getTrack 0 May 22 16:56:04 vol-ws volumio[3623]: info: CoreCommandRouter::volumioPushState May 22 16:56:04 vol-ws volumio[3623]: info: DAC script executed May 22 16:56:04 vol-ws volumio[3623]: info: Completed loading Core Plugins May 22 16:56:04 vol-ws volumio[3623]: info: Preparing to generate the ALSA configuration file May 22 16:56:04 vol-ws volumio[3623]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 22 16:56:04 vol-ws volumio[3623]: info: Reading ALSA contributions from plugins. May 22 16:56:04 vol-ws volumio[3623]: info: Discovery: adding 544166b9-d1dd-4d34-96ac-979983e6a685 May 22 16:56:04 vol-ws volumio[3623]: info: Discovery: Found device Vol-WS May 22 16:56:04 vol-ws volumio[3623]: info: CoreCommandRouter::volumioGetState May 22 16:56:04 vol-ws volumio[3623]: info: CorePlayQueue::getTrack 0 May 22 16:56:04 vol-ws volumio[3623]: info: Asound.conf file unchanged, so no further update is needed May 22 16:56:04 vol-ws volumio[3623]: info: Output device has changed, restarting MPD May 22 16:56:04 vol-ws volumio[3623]: info: ___________ START PLUGINS ___________ May 22 16:56:04 vol-ws volumio[3623]: info: ControllerMpd::onStart: Initializing MPD May 22 16:56:04 vol-ws volumio[3623]: info: Creating MPD Configuration file May 22 16:56:04 vol-ws sudo[3716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 16:56:04 vol-ws sudo[3716]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:04 vol-ws sudo[3716]: pam_unix(sudo:session): session closed for user root May 22 16:56:04 vol-ws sudo[3717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 16:56:04 vol-ws sudo[3717]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:04 vol-ws volumio[3623]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 16:56:04 vol-ws volumio[3623]: info: [1747925764578] CoreMusicLibrary::Adding element Last_100 May 22 16:56:04 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:56:04 vol-ws volumio[3623]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 16:56:04 vol-ws volumio[3623]: info: [1747925764588] CoreMusicLibrary::Adding element Webradio May 22 16:56:04 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:56:04 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:56:04 vol-ws sudo[3721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 16:56:04 vol-ws volumio[3623]: info: Initializing BBC Radios May 22 16:56:04 vol-ws sudo[3721]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:04 vol-ws sudo[3723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 16:56:04 vol-ws sudo[3723]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:04 vol-ws sudo[3721]: pam_unix(sudo:session): session closed for user root May 22 16:56:04 vol-ws systemd[1]: Stopping Music Player Daemon... May 22 16:56:04 vol-ws systemd[1]: mpd.service: Succeeded. May 22 16:56:04 vol-ws systemd[1]: Stopped Music Player Daemon. May 22 16:56:04 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:56:04 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:56:04 vol-ws systemd[1]: Starting Music Player Daemon... May 22 16:56:04 vol-ws volumio[3623]: info: [squeezelite_mc] Starting proxy server... May 22 16:56:04 vol-ws volumio[3623]: info: Volumio Calling Home May 22 16:56:04 vol-ws volumio[3623]: info: [squeezelite_mc] Proxy server started on port 46803 May 22 16:56:04 vol-ws sudo[3733]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 16:56:04 vol-ws sudo[3733]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:04 vol-ws sudo[3733]: pam_unix(sudo:session): session closed for user root May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:56:05 vol-ws volumio[3623]: info: MPD Permissions set May 22 16:56:05 vol-ws volumio[3623]: info: MPD Permissions set May 22 16:56:05 vol-ws volumio[3623]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: Starting albumart workers May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: Starting albumart workers May 22 16:56:05 vol-ws volumio[3623]: info: CoreStateMachine::pushState May 22 16:56:05 vol-ws volumio[3623]: info: CorePlayQueue::getTrack 0 May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::volumioPushState May 22 16:56:05 vol-ws volumio[3623]: info: [squeezelite_mc] Server discovery started May 22 16:56:05 vol-ws volumio[3623]: info: [squeezelite_mc] Player finder started May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:05 vol-ws volumio[3623]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 22 16:56:05 vol-ws volumio[3623]: Starting albumart workers May 22 16:56:05 vol-ws volumio[3623]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 16:56:05 vol-ws volumio[3623]: Error: send ENETUNREACH 255.255.255.255:3483 May 22 16:56:05 vol-ws volumio[3623]: at doSend (dgram.js:692:16) May 22 16:56:05 vol-ws volumio[3623]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 22 16:56:05 vol-ws volumio[3623]: at afterDns (dgram.js:638:5) May 22 16:56:05 vol-ws volumio[3623]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 22 16:56:05 vol-ws volumio[3623]: errno: -101, May 22 16:56:05 vol-ws volumio[3623]: code: 'ENETUNREACH', May 22 16:56:05 vol-ws volumio[3623]: syscall: 'send', May 22 16:56:05 vol-ws volumio[3623]: address: '255.255.255.255', May 22 16:56:05 vol-ws volumio[3623]: port: 3483 May 22 16:56:05 vol-ws volumio[3623]: } May 22 16:56:05 vol-ws volumio[3623]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 16:56:05 vol-ws kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 May 22 16:56:06 vol-ws sudo[3791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 16:55 May 22 16:56:06 vol-ws sudo[3791]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:06 vol-ws sudo[3791]: pam_unix(sudo:session): session closed for user root May 22 16:56:06 vol-ws volumio-remote-updater[635]: [2025-05-22 16:56:06] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 22 16:56:06 vol-ws volumio-remote-updater[635]: [2025-05-22 16:56:06] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 22 16:56:06 vol-ws systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 22 16:56:06 vol-ws sudo[3717]: pam_unix(sudo:session): session closed for user root May 22 16:56:06 vol-ws sudo[3723]: pam_unix(sudo:session): session closed for user root May 22 16:56:06 vol-ws systemd[1]: volumio.service: Failed with result 'exit-code'. May 22 16:56:06 vol-ws systemd[1]: Started dynamicswap service. May 22 16:56:06 vol-ws systemd[1]: dynamicswap.service: Succeeded. May 22 16:56:07 vol-ws mpd[3740]: May 22 16:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 22 16:56:07 vol-ws systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 22 16:56:07 vol-ws systemd[1]: volumio.service: Scheduled restart job, restart counter is at 11. May 22 16:56:07 vol-ws systemd[1]: Started Music Player Daemon. May 22 16:56:07 vol-ws systemd[1]: Stopped Volumio Backend Module. May 22 16:56:07 vol-ws systemd[1]: Started Volumio Backend Module. May 22 16:56:07 vol-ws systemd[1]: Started dynamicswap service. May 22 16:56:07 vol-ws systemd[1]: dynamicswap.service: Succeeded. May 22 16:56:07 vol-ws kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off May 22 16:56:07 vol-ws ifplugd(eth0)[805]: Link beat detected. May 22 16:56:07 vol-ws ifplugd(eth0)[805]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 22 16:56:07 vol-ws dhcpcd[3838]: DUID 00:01:00:01:2c:c5:40:e7:b8:27:eb:c9:de:82 May 22 16:56:07 vol-ws ifplugd(eth0)[805]: client: DUID 00:01:00:01:2c:c5:40:e7:b8:27:eb:c9:de:82 May 22 16:56:07 vol-ws dhcpcd[3838]: eth0: IAID eb:c9:de:82 May 22 16:56:07 vol-ws ifplugd(eth0)[805]: client: eth0: IAID eb:c9:de:82 May 22 16:56:07 vol-ws dhcpcd[3838]: eth0: adding address fe80::c838:5f13:bfe2:d688 May 22 16:56:07 vol-ws dhcpcd[3838]: ipv6_addaddr1: Permission denied May 22 16:56:07 vol-ws ifplugd(eth0)[805]: client: eth0: adding address fe80::c838:5f13:bfe2:d688 May 22 16:56:07 vol-ws ifplugd(eth0)[805]: client: ipv6_addaddr1: Permission denied May 22 16:56:08 vol-ws dhcpcd[3838]: eth0: soliciting a DHCP lease May 22 16:56:08 vol-ws ifplugd(eth0)[805]: client: eth0: soliciting a DHCP lease May 22 16:56:08 vol-ws dhcpcd[3838]: eth0: soliciting an IPv6 router May 22 16:56:08 vol-ws ifplugd(eth0)[805]: client: eth0: soliciting an IPv6 router May 22 16:56:09 vol-ws volumio[3806]: info: ------------------------------------------- May 22 16:56:09 vol-ws volumio[3806]: info: ----- Volumio3 ---- May 22 16:56:09 vol-ws volumio[3806]: info: ------------------------------------------- May 22 16:56:09 vol-ws volumio[3806]: info: ----- System startup ---- May 22 16:56:09 vol-ws volumio[3806]: info: ------------------------------------------- May 22 16:56:09 vol-ws volumio[3806]: info: MYVOLUMIO Environment detected May 22 16:56:09 vol-ws volumio[3806]: info: Plugin folders cleanup May 22 16:56:09 vol-ws volumio[3806]: info: Scanning into folder /volumio/app/plugins/ May 22 16:56:09 vol-ws volumio[3806]: info: Scanning category audio_interface May 22 16:56:09 vol-ws volumio[3806]: info: Scanning category miscellanea May 22 16:56:09 vol-ws volumio[3806]: info: Scanning category music_service May 22 16:56:09 vol-ws volumio[3806]: info: Scanning category plugins.json May 22 16:56:09 vol-ws volumio[3806]: info: Scanning category system_controller May 22 16:56:09 vol-ws volumio[3806]: info: Scanning category user_interface May 22 16:56:09 vol-ws volumio[3806]: info: Scanning into folder /data/plugins/ May 22 16:56:09 vol-ws volumio[3806]: info: Scanning category music_service May 22 16:56:09 vol-ws volumio[3806]: info: Plugin folders cleanup completed May 22 16:56:09 vol-ws volumio[3806]: info: ------------------------------------------- May 22 16:56:09 vol-ws volumio[3806]: info: ----- Core plugins startup ---- May 22 16:56:09 vol-ws volumio[3806]: info: ------------------------------------------- May 22 16:56:09 vol-ws volumio[3806]: info: Loading plugins from folder /volumio/app/plugins/ May 22 16:56:09 vol-ws volumio[3806]: info: Adding plugin upnp to MyMusic Plugins May 22 16:56:09 vol-ws volumio[3806]: info: Adding plugin airplay_emulation to MyMusic Plugins May 22 16:56:09 vol-ws volumio[3806]: info: Adding plugin upnp_browser to MyMusic Plugins May 22 16:56:09 vol-ws volumio[3806]: info: Loading plugins from folder /data/plugins/ May 22 16:56:09 vol-ws volumio[3806]: info: Loading plugin "system"... May 22 16:56:09 vol-ws volumio[3806]: info: Loading plugin "appearance"... May 22 16:56:10 vol-ws volumio[3806]: info: Loading plugin "network"... May 22 16:56:10 vol-ws volumio[3806]: info: Refreshing Cached IP Addresses May 22 16:56:10 vol-ws sudo[3855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 16:56:10 vol-ws sudo[3855]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:10 vol-ws sudo[3855]: pam_unix(sudo:session): session closed for user root May 22 16:56:10 vol-ws sudo[3857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 16:56:10 vol-ws volumio[3806]: info: Loading plugin "services"... May 22 16:56:10 vol-ws sudo[3857]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:10 vol-ws volumio[3806]: info: Loading plugin "alsa_controller"... May 22 16:56:11 vol-ws sudo[3857]: pam_unix(sudo:session): session closed for user root May 22 16:56:11 vol-ws sudo[3860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 22 16:56:11 vol-ws sudo[3860]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:11 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:56:11 vol-ws volumio[3806]: info: Loading plugin "wizard"... May 22 16:56:11 vol-ws volumio[3806]: info: Loading plugin "networkfs"... May 22 16:56:11 vol-ws volumio[3806]: info: Cannot mount NAS audio at system boot, trial number 1 ,retrying in 5 seconds May 22 16:56:11 vol-ws volumio[3806]: info: Starting Udev Watcher for removable devices May 22 16:56:11 vol-ws volumio[3806]: info: Ignoring mount for partition: boot May 22 16:56:11 vol-ws volumio[3806]: info: Ignoring mount for partition: volumio May 22 16:56:11 vol-ws volumio[3806]: info: Ignoring mount for partition: volumio_data May 22 16:56:11 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 16:56:11 vol-ws volumio[3806]: info: Loading plugin "volumio_command_line_client"... May 22 16:56:11 vol-ws volumio[3806]: info: Plugin upnp is not enabled May 22 16:56:11 vol-ws volumio[3806]: info: Loading plugin "my_music"... May 22 16:56:11 vol-ws volumio[3806]: info: Loading plugin "mpd"... May 22 16:56:11 vol-ws volumio[3806]: info: Plugin upnp_browser is not enabled May 22 16:56:11 vol-ws volumio[3806]: info: Loading plugin "alarm-clock"... May 22 16:56:11 vol-ws volumio[3806]: info: Plugin airplay_emulation is not enabled May 22 16:56:11 vol-ws volumio[3806]: info: Loading plugin "last_100"... May 22 16:56:11 vol-ws volumio[3806]: info: Loading plugin "webradio"... May 22 16:56:11 vol-ws volumio[3806]: info: Loading plugin "i2s_dacs"... May 22 16:56:11 vol-ws volumio-remote-updater[635]: [2025-05-22 16:56:11] [connect] Successful connection May 22 16:56:11 vol-ws volumio[3806]: info: Loading plugin "volumiodiscovery"... May 22 16:56:11 vol-ws volumio[3806]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 16:56:11 vol-ws volumio[3806]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:56:11 vol-ws volumio[3806]: *** WARNING *** For more information see May 22 16:56:11 vol-ws node[3806]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 16:56:11 vol-ws volumio[3806]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 16:56:11 vol-ws volumio[3806]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:56:11 vol-ws volumio[3806]: *** WARNING *** For more information see May 22 16:56:11 vol-ws node[3806]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:56:11 vol-ws node[3806]: *** WARNING *** For more information see May 22 16:56:11 vol-ws node[3806]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 16:56:11 vol-ws node[3806]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:56:11 vol-ws node[3806]: *** WARNING *** For more information see May 22 16:56:11 vol-ws volumio[3806]: info: Applying required configuration parameters for plugin volumiodiscovery May 22 16:56:11 vol-ws volumio[3806]: info: Discovery: Started advertising with name: Vol-WS May 22 16:56:11 vol-ws sudo[3860]: pam_unix(sudo:session): session closed for user root May 22 16:56:11 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 16:56:11 vol-ws volumio[3806]: info: Loading plugin "squeezelite_mc"... May 22 16:56:12 vol-ws dhcpcd[3838]: eth0: carrier lost May 22 16:56:12 vol-ws ifplugd(eth0)[805]: client: eth0: carrier lost May 22 16:56:12 vol-ws kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down May 22 16:56:12 vol-ws volumio[3806]: info: Loading plugin "outputs"... May 22 16:56:12 vol-ws volumio[3806]: info: Loading plugin "albumart"... May 22 16:56:12 vol-ws volumio[3806]: info: Plugin example_plugin is not enabled May 22 16:56:12 vol-ws volumio[3806]: info: Loading plugin "inputs"... May 22 16:56:12 vol-ws volumio[3806]: info: Loading plugin "updater_comm"... May 22 16:56:12 vol-ws volumio[3806]: info: Plugin mpdemulation is not enabled May 22 16:56:12 vol-ws volumio[3806]: info: Loading plugin "rest_api"... May 22 16:56:13 vol-ws volumio[3806]: info: Loading plugin "websocket"... May 22 16:56:13 vol-ws volumio[3806]: info: Starting Socket.io Server version 2.3.0 May 22 16:56:13 vol-ws volumio[3806]: info: Loading i18n strings for locale en May 22 16:56:13 vol-ws volumio[3806]: Updating browse sources language May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:56:13 vol-ws volumio[3806]: Forking 3 albumart workers May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::initPlayerControls May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 16:56:13 vol-ws volumio[3806]: Express server listening on port 3000 May 22 16:56:13 vol-ws volumio[3806]: [Metrics] WebUI: 4s 801.10ms May 22 16:56:13 vol-ws volumio[3806]: info: CoreStateMachine::resetVolumioState May 22 16:56:13 vol-ws volumio[3806]: info: CoreStateMachine::getcurrentVolume May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:56:13 vol-ws volumio[3806]: info: Cannot read play queue from file May 22 16:56:13 vol-ws volumio-remote-updater[635]: [2025-05-22 16:56:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747925771 101 May 22 16:56:13 vol-ws volumio[3806]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 May 22 16:56:13 vol-ws volumio[3806]: info: CoreStateMachine::pushState May 22 16:56:13 vol-ws volumio[3806]: info: CorePlayQueue::getTrack 0 May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::volumioPushState May 22 16:56:13 vol-ws volumio[3806]: info: CoreStateMachine::updateTrackBlock May 22 16:56:13 vol-ws volumio[3806]: info: CorePlayQueue::getTrackBlock May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:56:13 vol-ws volumio[3806]: info: Executing start script for DAC IQaudIO DAC Plus May 22 16:56:13 vol-ws volumio[3806]: info: CoreStateMachine::setRepeat null single undefined May 22 16:56:13 vol-ws volumio[3806]: info: CoreStateMachine::pushState May 22 16:56:13 vol-ws volumio[3806]: info: CorePlayQueue::getTrack 0 May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::volumioPushState May 22 16:56:13 vol-ws volumio[3806]: info: CoreStateMachine::setRandom null May 22 16:56:13 vol-ws volumio[3806]: info: CoreStateMachine::pushState May 22 16:56:13 vol-ws volumio[3806]: info: CorePlayQueue::getTrack 0 May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::volumioPushState May 22 16:56:13 vol-ws volumio[3806]: info: Setting Device type: Raspberry PI May 22 16:56:13 vol-ws volumio[3806]: info: CoreStateMachine::pushState May 22 16:56:13 vol-ws volumio[3806]: info: CorePlayQueue::getTrack 0 May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::volumioPushState May 22 16:56:13 vol-ws volumio[3806]: info: DAC script executed May 22 16:56:13 vol-ws volumio[3806]: info: Completed loading Core Plugins May 22 16:56:13 vol-ws volumio[3806]: info: Preparing to generate the ALSA configuration file May 22 16:56:13 vol-ws volumio[3806]: info: Discovery: adding 544166b9-d1dd-4d34-96ac-979983e6a685 May 22 16:56:13 vol-ws volumio[3806]: info: Discovery: Found device Vol-WS May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::volumioGetState May 22 16:56:13 vol-ws volumio[3806]: info: CorePlayQueue::getTrack 0 May 22 16:56:13 vol-ws volumio[3806]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 22 16:56:13 vol-ws volumio[3806]: info: Reading ALSA contributions from plugins. May 22 16:56:13 vol-ws volumio[3806]: info: Asound.conf file unchanged, so no further update is needed May 22 16:56:13 vol-ws volumio[3806]: info: Output device has changed, restarting MPD May 22 16:56:13 vol-ws volumio[3806]: info: ___________ START PLUGINS ___________ May 22 16:56:13 vol-ws volumio[3806]: info: ControllerMpd::onStart: Initializing MPD May 22 16:56:13 vol-ws volumio[3806]: info: Creating MPD Configuration file May 22 16:56:13 vol-ws sudo[3941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 16:56:13 vol-ws sudo[3942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 16:56:13 vol-ws sudo[3942]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:13 vol-ws sudo[3941]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 16:56:13 vol-ws volumio[3806]: info: [1747925773890] CoreMusicLibrary::Adding element Last_100 May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:56:13 vol-ws sudo[3941]: pam_unix(sudo:session): session closed for user root May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 16:56:13 vol-ws volumio[3806]: info: [1747925773916] CoreMusicLibrary::Adding element Webradio May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:56:13 vol-ws sudo[3944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 16:56:13 vol-ws sudo[3944]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:13 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:56:13 vol-ws systemd[1]: Stopping Music Player Daemon... May 22 16:56:13 vol-ws sudo[3948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 16:56:13 vol-ws sudo[3948]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:13 vol-ws sudo[3944]: pam_unix(sudo:session): session closed for user root May 22 16:56:13 vol-ws volumio[3806]: info: Initializing BBC Radios May 22 16:56:13 vol-ws systemd[1]: mpd.service: Succeeded. May 22 16:56:13 vol-ws systemd[1]: Stopped Music Player Daemon. May 22 16:56:14 vol-ws systemd[1]: Starting Music Player Daemon... May 22 16:56:14 vol-ws systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 22 16:56:14 vol-ws systemd[1]: mpd.service: Succeeded. May 22 16:56:14 vol-ws systemd[1]: Stopped Music Player Daemon. May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:56:14 vol-ws systemd[1]: Starting Music Player Daemon... May 22 16:56:14 vol-ws volumio[3806]: info: [squeezelite_mc] Starting proxy server... May 22 16:56:14 vol-ws volumio[3806]: info: Volumio Calling Home May 22 16:56:14 vol-ws sudo[3962]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 16:56:14 vol-ws sudo[3962]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:14 vol-ws sudo[3962]: pam_unix(sudo:session): session closed for user root May 22 16:56:14 vol-ws volumio[3806]: info: [squeezelite_mc] Proxy server started on port 43587 May 22 16:56:14 vol-ws volumio[3806]: Starting albumart workers May 22 16:56:14 vol-ws volumio[3806]: Starting albumart workers May 22 16:56:14 vol-ws volumio[3806]: Starting albumart workers May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:56:14 vol-ws volumio[3806]: info: MPD Permissions set May 22 16:56:14 vol-ws volumio[3806]: info: MPD Permissions set May 22 16:56:14 vol-ws volumio[3806]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreStateMachine::pushState May 22 16:56:14 vol-ws volumio[3806]: info: CorePlayQueue::getTrack 0 May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::volumioPushState May 22 16:56:14 vol-ws volumio[3806]: info: [squeezelite_mc] Server discovery started May 22 16:56:14 vol-ws volumio[3806]: info: [squeezelite_mc] Player finder started May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:14 vol-ws volumio[3806]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 22 16:56:15 vol-ws volumio[3806]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 16:56:15 vol-ws volumio[3806]: Error: send ENETUNREACH 255.255.255.255:3483 May 22 16:56:15 vol-ws volumio[3806]: at doSend (dgram.js:692:16) May 22 16:56:15 vol-ws volumio[3806]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 22 16:56:15 vol-ws volumio[3806]: at afterDns (dgram.js:638:5) May 22 16:56:15 vol-ws volumio[3806]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 22 16:56:15 vol-ws volumio[3806]: errno: -101, May 22 16:56:15 vol-ws volumio[3806]: code: 'ENETUNREACH', May 22 16:56:15 vol-ws volumio[3806]: syscall: 'send', May 22 16:56:15 vol-ws volumio[3806]: address: '255.255.255.255', May 22 16:56:15 vol-ws volumio[3806]: port: 3483 May 22 16:56:15 vol-ws volumio[3806]: } May 22 16:56:15 vol-ws volumio[3806]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 16:56:15 vol-ws dhcpcd[3838]: eth0: carrier acquired May 22 16:56:15 vol-ws ifplugd(eth0)[805]: client: eth0: carrier acquired May 22 16:56:15 vol-ws kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off May 22 16:56:15 vol-ws dhcpcd[3838]: eth0: IAID eb:c9:de:82 May 22 16:56:15 vol-ws ifplugd(eth0)[805]: client: eth0: IAID eb:c9:de:82 May 22 16:56:15 vol-ws kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 May 22 16:56:15 vol-ws dhcpcd[3838]: eth0: soliciting an IPv6 router May 22 16:56:15 vol-ws ifplugd(eth0)[805]: client: eth0: soliciting an IPv6 router May 22 16:56:15 vol-ws sudo[4018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 16:55 May 22 16:56:15 vol-ws sudo[4018]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:15 vol-ws dhcpcd[3838]: eth0: soliciting a DHCP lease May 22 16:56:15 vol-ws ifplugd(eth0)[805]: client: eth0: soliciting a DHCP lease May 22 16:56:15 vol-ws sudo[4018]: pam_unix(sudo:session): session closed for user root May 22 16:56:16 vol-ws volumio-remote-updater[635]: [2025-05-22 16:56:16] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 22 16:56:16 vol-ws volumio-remote-updater[635]: [2025-05-22 16:56:16] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 22 16:56:16 vol-ws systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 22 16:56:16 vol-ws sudo[3942]: pam_unix(sudo:session): session closed for user root May 22 16:56:16 vol-ws sudo[3948]: pam_unix(sudo:session): session closed for user root May 22 16:56:16 vol-ws systemd[1]: volumio.service: Failed with result 'exit-code'. May 22 16:56:16 vol-ws systemd[1]: Started dynamicswap service. May 22 16:56:16 vol-ws systemd[1]: dynamicswap.service: Succeeded. May 22 16:56:16 vol-ws mpd[3966]: May 22 16:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 22 16:56:16 vol-ws systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 22 16:56:16 vol-ws systemd[1]: volumio.service: Scheduled restart job, restart counter is at 12. May 22 16:56:16 vol-ws systemd[1]: Stopped Volumio Backend Module. May 22 16:56:16 vol-ws systemd[1]: Started Volumio Backend Module. May 22 16:56:16 vol-ws systemd[1]: Started dynamicswap service. May 22 16:56:16 vol-ws systemd[1]: Started Music Player Daemon. May 22 16:56:16 vol-ws systemd[1]: dynamicswap.service: Succeeded. May 22 16:56:18 vol-ws volumio[4033]: info: ------------------------------------------- May 22 16:56:18 vol-ws volumio[4033]: info: ----- Volumio3 ---- May 22 16:56:18 vol-ws volumio[4033]: info: ------------------------------------------- May 22 16:56:18 vol-ws volumio[4033]: info: ----- System startup ---- May 22 16:56:18 vol-ws volumio[4033]: info: ------------------------------------------- May 22 16:56:19 vol-ws volumio[4033]: info: MYVOLUMIO Environment detected May 22 16:56:19 vol-ws volumio[4033]: info: Plugin folders cleanup May 22 16:56:19 vol-ws volumio[4033]: info: Scanning into folder /volumio/app/plugins/ May 22 16:56:19 vol-ws volumio[4033]: info: Scanning category audio_interface May 22 16:56:19 vol-ws volumio[4033]: info: Scanning category miscellanea May 22 16:56:19 vol-ws volumio[4033]: info: Scanning category music_service May 22 16:56:19 vol-ws volumio[4033]: info: Scanning category plugins.json May 22 16:56:19 vol-ws volumio[4033]: info: Scanning category system_controller May 22 16:56:19 vol-ws volumio[4033]: info: Scanning category user_interface May 22 16:56:19 vol-ws volumio[4033]: info: Scanning into folder /data/plugins/ May 22 16:56:19 vol-ws volumio[4033]: info: Scanning category music_service May 22 16:56:19 vol-ws volumio[4033]: info: Plugin folders cleanup completed May 22 16:56:19 vol-ws volumio[4033]: info: ------------------------------------------- May 22 16:56:19 vol-ws volumio[4033]: info: ----- Core plugins startup ---- May 22 16:56:19 vol-ws volumio[4033]: info: ------------------------------------------- May 22 16:56:19 vol-ws volumio[4033]: info: Loading plugins from folder /volumio/app/plugins/ May 22 16:56:19 vol-ws volumio[4033]: info: Adding plugin upnp to MyMusic Plugins May 22 16:56:19 vol-ws volumio[4033]: info: Adding plugin airplay_emulation to MyMusic Plugins May 22 16:56:19 vol-ws volumio[4033]: info: Adding plugin upnp_browser to MyMusic Plugins May 22 16:56:19 vol-ws volumio[4033]: info: Loading plugins from folder /data/plugins/ May 22 16:56:19 vol-ws volumio[4033]: info: Loading plugin "system"... May 22 16:56:19 vol-ws volumio[4033]: info: Loading plugin "appearance"... May 22 16:56:20 vol-ws dhcpcd[3838]: eth0: offered 10.10.10.82 from 10.10.10.1 May 22 16:56:20 vol-ws ifplugd(eth0)[805]: client: eth0: offered 10.10.10.82 from 10.10.10.1 May 22 16:56:20 vol-ws dhcpcd[3838]: eth0: probing address 10.10.10.82/24 May 22 16:56:20 vol-ws ifplugd(eth0)[805]: client: eth0: probing address 10.10.10.82/24 May 22 16:56:20 vol-ws volumio[4033]: info: Loading plugin "network"... May 22 16:56:20 vol-ws volumio[4033]: info: Refreshing Cached IP Addresses May 22 16:56:20 vol-ws sudo[4098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 16:56:20 vol-ws sudo[4098]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:20 vol-ws sudo[4098]: pam_unix(sudo:session): session closed for user root May 22 16:56:20 vol-ws volumio[4033]: info: Loading plugin "services"... May 22 16:56:20 vol-ws sudo[4100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 16:56:20 vol-ws sudo[4100]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:20 vol-ws volumio[4033]: info: Loading plugin "alsa_controller"... May 22 16:56:20 vol-ws sudo[4100]: pam_unix(sudo:session): session closed for user root May 22 16:56:20 vol-ws sudo[4103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 22 16:56:20 vol-ws sudo[4103]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:20 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:56:20 vol-ws volumio[4033]: info: Loading plugin "wizard"... May 22 16:56:20 vol-ws volumio[4033]: info: Loading plugin "networkfs"... May 22 16:56:20 vol-ws volumio[4033]: info: Cannot mount NAS audio at system boot, trial number 1 ,retrying in 5 seconds May 22 16:56:20 vol-ws volumio[4033]: info: Starting Udev Watcher for removable devices May 22 16:56:20 vol-ws volumio[4033]: info: Ignoring mount for partition: boot May 22 16:56:20 vol-ws volumio[4033]: info: Ignoring mount for partition: volumio May 22 16:56:20 vol-ws volumio[4033]: info: Ignoring mount for partition: volumio_data May 22 16:56:20 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 16:56:20 vol-ws volumio[4033]: info: Loading plugin "volumio_command_line_client"... May 22 16:56:20 vol-ws volumio[4033]: info: Plugin upnp is not enabled May 22 16:56:20 vol-ws volumio[4033]: info: Loading plugin "my_music"... May 22 16:56:20 vol-ws volumio[4033]: info: Loading plugin "mpd"... May 22 16:56:20 vol-ws volumio[4033]: info: Plugin upnp_browser is not enabled May 22 16:56:20 vol-ws volumio[4033]: info: Loading plugin "alarm-clock"... May 22 16:56:21 vol-ws volumio[4033]: info: Plugin airplay_emulation is not enabled May 22 16:56:21 vol-ws volumio[4033]: info: Loading plugin "last_100"... May 22 16:56:21 vol-ws volumio[4033]: info: Loading plugin "webradio"... May 22 16:56:21 vol-ws volumio-remote-updater[635]: [2025-05-22 16:56:21] [connect] Successful connection May 22 16:56:21 vol-ws volumio[4033]: info: Loading plugin "i2s_dacs"... May 22 16:56:21 vol-ws volumio[4033]: info: Loading plugin "volumiodiscovery"... May 22 16:56:21 vol-ws volumio[4033]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 16:56:21 vol-ws volumio[4033]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:56:21 vol-ws volumio[4033]: *** WARNING *** For more information see May 22 16:56:21 vol-ws node[4033]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 16:56:21 vol-ws volumio[4033]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 16:56:21 vol-ws volumio[4033]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:56:21 vol-ws volumio[4033]: *** WARNING *** For more information see May 22 16:56:21 vol-ws node[4033]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:56:21 vol-ws node[4033]: *** WARNING *** For more information see May 22 16:56:21 vol-ws node[4033]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 16:56:21 vol-ws node[4033]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 16:56:21 vol-ws node[4033]: *** WARNING *** For more information see May 22 16:56:21 vol-ws volumio[4033]: info: Applying required configuration parameters for plugin volumiodiscovery May 22 16:56:21 vol-ws volumio[4033]: info: Discovery: Started advertising with name: Vol-WS May 22 16:56:21 vol-ws sudo[4103]: pam_unix(sudo:session): session closed for user root May 22 16:56:21 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 16:56:21 vol-ws volumio[4033]: info: Loading plugin "squeezelite_mc"... May 22 16:56:22 vol-ws volumio[4033]: info: Loading plugin "outputs"... May 22 16:56:22 vol-ws volumio[4033]: info: Loading plugin "albumart"... May 22 16:56:22 vol-ws volumio[4033]: info: Plugin example_plugin is not enabled May 22 16:56:22 vol-ws volumio[4033]: info: Loading plugin "inputs"... May 22 16:56:22 vol-ws volumio[4033]: info: Loading plugin "updater_comm"... May 22 16:56:22 vol-ws volumio[4033]: info: Plugin mpdemulation is not enabled May 22 16:56:22 vol-ws volumio[4033]: info: Loading plugin "rest_api"... May 22 16:56:22 vol-ws volumio[4033]: info: Loading plugin "websocket"... May 22 16:56:22 vol-ws volumio[4033]: info: Starting Socket.io Server version 2.3.0 May 22 16:56:22 vol-ws volumio[4033]: info: Loading i18n strings for locale en May 22 16:56:22 vol-ws volumio[4033]: Updating browse sources language May 22 16:56:22 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:56:22 vol-ws volumio[4033]: Forking 3 albumart workers May 22 16:56:22 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 16:56:22 vol-ws volumio[4033]: info: CoreCommandRouter::initPlayerControls May 22 16:56:22 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:22 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:22 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:22 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:22 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:22 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:22 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:22 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:22 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 16:56:22 vol-ws volumio[4033]: Express server listening on port 3000 May 22 16:56:22 vol-ws volumio[4033]: [Metrics] WebUI: 4s 797.11ms May 22 16:56:22 vol-ws volumio[4033]: info: CoreStateMachine::resetVolumioState May 22 16:56:22 vol-ws volumio[4033]: info: CoreStateMachine::getcurrentVolume May 22 16:56:22 vol-ws volumio[4033]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:56:22 vol-ws volumio[4033]: info: Cannot read play queue from file May 22 16:56:22 vol-ws volumio-remote-updater[635]: [2025-05-22 16:56:22] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747925781 101 May 22 16:56:22 vol-ws volumio[4033]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 May 22 16:56:22 vol-ws volumio[4033]: info: CoreStateMachine::pushState May 22 16:56:22 vol-ws volumio[4033]: info: CorePlayQueue::getTrack 0 May 22 16:56:22 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 16:56:22 vol-ws volumio[4033]: info: CoreCommandRouter::volumioPushState May 22 16:56:22 vol-ws volumio[4033]: info: CoreStateMachine::updateTrackBlock May 22 16:56:22 vol-ws volumio[4033]: info: CorePlayQueue::getTrackBlock May 22 16:56:22 vol-ws volumio[4033]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:56:22 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:56:22 vol-ws volumio[4033]: info: Executing start script for DAC IQaudIO DAC Plus May 22 16:56:22 vol-ws volumio[4033]: info: CoreStateMachine::setRepeat null single undefined May 22 16:56:22 vol-ws volumio[4033]: info: CoreStateMachine::pushState May 22 16:56:22 vol-ws volumio[4033]: info: CorePlayQueue::getTrack 0 May 22 16:56:22 vol-ws volumio[4033]: info: CoreCommandRouter::volumioPushState May 22 16:56:22 vol-ws volumio[4033]: info: CoreStateMachine::setRandom null May 22 16:56:22 vol-ws volumio[4033]: info: CoreStateMachine::pushState May 22 16:56:22 vol-ws volumio[4033]: info: CorePlayQueue::getTrack 0 May 22 16:56:22 vol-ws volumio[4033]: info: CoreCommandRouter::volumioPushState May 22 16:56:22 vol-ws volumio[4033]: info: Setting Device type: Raspberry PI May 22 16:56:23 vol-ws volumio[4033]: info: CoreStateMachine::pushState May 22 16:56:23 vol-ws volumio[4033]: info: CorePlayQueue::getTrack 0 May 22 16:56:23 vol-ws volumio[4033]: info: CoreCommandRouter::volumioPushState May 22 16:56:23 vol-ws volumio[4033]: info: Discovery: adding 544166b9-d1dd-4d34-96ac-979983e6a685 May 22 16:56:23 vol-ws volumio[4033]: info: Discovery: Found device Vol-WS May 22 16:56:23 vol-ws volumio[4033]: info: CoreCommandRouter::volumioGetState May 22 16:56:23 vol-ws volumio[4033]: info: CorePlayQueue::getTrack 0 May 22 16:56:23 vol-ws volumio[4033]: info: DAC script executed May 22 16:56:23 vol-ws volumio[4033]: info: Completed loading Core Plugins May 22 16:56:23 vol-ws volumio[4033]: info: Preparing to generate the ALSA configuration file May 22 16:56:23 vol-ws volumio[4033]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 22 16:56:23 vol-ws volumio[4033]: info: Reading ALSA contributions from plugins. May 22 16:56:23 vol-ws volumio[4033]: info: Asound.conf file unchanged, so no further update is needed May 22 16:56:23 vol-ws volumio[4033]: info: Output device has changed, restarting MPD May 22 16:56:23 vol-ws volumio[4033]: info: ___________ START PLUGINS ___________ May 22 16:56:23 vol-ws volumio[4033]: info: ControllerMpd::onStart: Initializing MPD May 22 16:56:23 vol-ws volumio[4033]: info: Creating MPD Configuration file May 22 16:56:23 vol-ws sudo[4165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 16:56:23 vol-ws sudo[4169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 16:56:23 vol-ws sudo[4169]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:23 vol-ws sudo[4166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 16:56:23 vol-ws sudo[4166]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:23 vol-ws volumio[4033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 16:56:23 vol-ws volumio[4033]: info: [1747925783206] CoreMusicLibrary::Adding element Last_100 May 22 16:56:23 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:56:23 vol-ws volumio[4033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 16:56:23 vol-ws volumio[4033]: info: [1747925783214] CoreMusicLibrary::Adding element Webradio May 22 16:56:23 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 16:56:23 vol-ws sudo[4169]: pam_unix(sudo:session): session closed for user root May 22 16:56:23 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:56:23 vol-ws sudo[4165]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:23 vol-ws volumio[4033]: info: Initializing BBC Radios May 22 16:56:23 vol-ws sudo[4165]: pam_unix(sudo:session): session closed for user root May 22 16:56:23 vol-ws systemd[1]: Stopping Music Player Daemon... May 22 16:56:23 vol-ws sudo[4172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 16:56:23 vol-ws sudo[4172]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:23 vol-ws systemd[1]: mpd.service: Succeeded. May 22 16:56:23 vol-ws systemd[1]: Stopped Music Player Daemon. May 22 16:56:23 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 16:56:23 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 16:56:23 vol-ws systemd[1]: Starting Music Player Daemon... May 22 16:56:23 vol-ws systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 22 16:56:23 vol-ws systemd[1]: mpd.service: Succeeded. May 22 16:56:23 vol-ws systemd[1]: Stopped Music Player Daemon. May 22 16:56:23 vol-ws systemd[1]: Starting Music Player Daemon... May 22 16:56:23 vol-ws volumio[4033]: info: [squeezelite_mc] Starting proxy server... May 22 16:56:23 vol-ws volumio[4033]: info: Volumio Calling Home May 22 16:56:23 vol-ws sudo[4187]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 16:56:23 vol-ws sudo[4187]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:23 vol-ws sudo[4187]: pam_unix(sudo:session): session closed for user root May 22 16:56:23 vol-ws volumio[4033]: info: [squeezelite_mc] Proxy server started on port 43519 May 22 16:56:23 vol-ws volumio[4033]: Starting albumart workers May 22 16:56:23 vol-ws volumio[4033]: info: CoreCommandRouter::volumioRetrievevolume May 22 16:56:23 vol-ws volumio[4033]: Starting albumart workers May 22 16:56:23 vol-ws volumio[4033]: Starting albumart workers May 22 16:56:23 vol-ws volumio[4033]: info: MPD Permissions set May 22 16:56:23 vol-ws volumio[4033]: info: MPD Permissions set May 22 16:56:24 vol-ws volumio[4033]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 22 16:56:24 vol-ws volumio[4033]: info: CoreStateMachine::pushState May 22 16:56:24 vol-ws volumio[4033]: info: CorePlayQueue::getTrack 0 May 22 16:56:24 vol-ws volumio[4033]: info: CoreCommandRouter::volumioPushState May 22 16:56:24 vol-ws volumio[4033]: info: [squeezelite_mc] Server discovery started May 22 16:56:24 vol-ws volumio[4033]: info: [squeezelite_mc] Player finder started May 22 16:56:24 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:24 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:24 vol-ws volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 16:56:24 vol-ws volumio[4033]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 22 16:56:24 vol-ws volumio[4033]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 16:56:24 vol-ws volumio[4033]: Error: send ENETUNREACH 255.255.255.255:3483 May 22 16:56:24 vol-ws volumio[4033]: at doSend (dgram.js:692:16) May 22 16:56:24 vol-ws volumio[4033]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 22 16:56:24 vol-ws volumio[4033]: at afterDns (dgram.js:638:5) May 22 16:56:24 vol-ws volumio[4033]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 22 16:56:24 vol-ws volumio[4033]: errno: -101, May 22 16:56:24 vol-ws volumio[4033]: code: 'ENETUNREACH', May 22 16:56:24 vol-ws volumio[4033]: syscall: 'send', May 22 16:56:24 vol-ws volumio[4033]: address: '255.255.255.255', May 22 16:56:24 vol-ws volumio[4033]: port: 3483 May 22 16:56:24 vol-ws volumio[4033]: } May 22 16:56:24 vol-ws volumio[4033]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 16:56:24 vol-ws kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 May 22 16:56:25 vol-ws dhcpcd[3838]: eth0: leased 10.10.10.82 for 86400 seconds May 22 16:56:25 vol-ws ifplugd(eth0)[805]: client: eth0: leased 10.10.10.82 for 86400 seconds May 22 16:56:25 vol-ws avahi-daemon[650]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.10.10.82. May 22 16:56:25 vol-ws dhcpcd[3838]: eth0: adding route to 10.10.10.0/24 May 22 16:56:25 vol-ws avahi-daemon[650]: New relevant interface eth0.IPv4 for mDNS. May 22 16:56:25 vol-ws avahi-daemon[650]: Registering new address record for 10.10.10.82 on eth0.IPv4. May 22 16:56:25 vol-ws dhcpcd[3838]: eth0: adding default route via 10.10.10.1 May 22 16:56:25 vol-ws ifplugd(eth0)[805]: client: eth0: adding route to 10.10.10.0/24 May 22 16:56:25 vol-ws ifplugd(eth0)[805]: client: eth0: adding default route via 10.10.10.1 May 22 16:56:25 vol-ws sudo[4241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 16:55 May 22 16:56:25 vol-ws sudo[4241]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 16:56:25 vol-ws dnsmasq[892]: reading /etc/resolv.conf May 22 16:56:25 vol-ws dnsmasq[892]: using nameserver 10.10.10.34#53 May 22 16:56:25 vol-ws dnsmasq[892]: using nameserver 10.10.10.30#53 May 22 16:56:25 vol-ws dnsmasq[892]: using nameserver 208.67.222.222#53 May 22 16:56:25 vol-ws dnsmasq[892]: using nameserver 208.67.220.220#53 May 22 16:56:25 vol-ws dhcpcd[3838]: forked to background, child pid 4259 May 22 16:56:25 vol-ws ifplugd(eth0)[805]: client: forked to background, child pid 4259 PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"