-- Logs begin at Fri 2025-10-31 03:56:08 EDT, end at Sat 2025-11-01 03:17:53 EDT. -- Nov 01 03:16:00 volumio-office sudo[10476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-01 03:15 Nov 01 03:16:00 volumio-office sudo[10476]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:00 volumio-office sudo[10476]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:00 volumio-office systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 01 03:16:00 volumio-office sudo[10353]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:00 volumio-office sudo[10414]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:00 volumio-office sudo[10420]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:00 volumio-office volumio-remote-updater[507]: [2025-11-01 03:16:00] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Nov 01 03:16:00 volumio-office volumio-remote-updater[507]: [2025-11-01 03:16:00] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Nov 01 03:16:00 volumio-office volumio-remote-updater[507]: [2025-11-01 03:16:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 01 03:16:00 volumio-office systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 01 03:16:00 volumio-office systemd[1]: Started dynamicswap service. Nov 01 03:16:00 volumio-office systemd[1]: dynamicswap.service: Succeeded. Nov 01 03:16:00 volumio-office systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 01 03:16:00 volumio-office systemd[1]: volumio.service: Scheduled restart job, restart counter is at 24. Nov 01 03:16:00 volumio-office systemd[1]: Started dynamicswap service. Nov 01 03:16:00 volumio-office systemd[1]: Stopped Volumio Backend Module. Nov 01 03:16:00 volumio-office systemd[1]: Started Volumio Backend Module. Nov 01 03:16:00 volumio-office systemd[1]: dynamicswap.service: Succeeded. Nov 01 03:16:01 volumio-office mpd[10437]: Nov 01 03:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 01 03:16:01 volumio-office systemd[1]: Started Music Player Daemon. Nov 01 03:16:02 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Nov 01 03:16:02 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Nov 01 03:16:02 volumio-office volumio[10490]: info: ------------------------------------------- Nov 01 03:16:02 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Nov 01 03:16:02 volumio-office volumio[10490]: info: ----- Volumio3 ---- Nov 01 03:16:02 volumio-office volumio[10490]: info: ------------------------------------------- Nov 01 03:16:02 volumio-office volumio[10490]: info: ----- System startup ---- Nov 01 03:16:02 volumio-office volumio[10490]: info: ------------------------------------------- Nov 01 03:16:02 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Nov 01 03:16:03 volumio-office volumio[10490]: info: MYVOLUMIO Environment detected Nov 01 03:16:03 volumio-office volumio[10490]: info: Plugin folders cleanup Nov 01 03:16:03 volumio-office volumio[10490]: info: Scanning into folder /volumio/app/plugins/ Nov 01 03:16:03 volumio-office volumio[10490]: info: Scanning category audio_interface Nov 01 03:16:03 volumio-office volumio[10490]: info: Scanning category miscellanea Nov 01 03:16:03 volumio-office volumio[10490]: info: Scanning category music_service Nov 01 03:16:03 volumio-office volumio[10490]: info: Scanning category plugins.json Nov 01 03:16:03 volumio-office volumio[10490]: info: Scanning category system_controller Nov 01 03:16:03 volumio-office volumio[10490]: info: Scanning category user_interface Nov 01 03:16:03 volumio-office volumio[10490]: info: Scanning into folder /data/plugins/ Nov 01 03:16:03 volumio-office volumio[10490]: info: Scanning category music_service Nov 01 03:16:03 volumio-office volumio[10490]: info: Scanning category user_interface Nov 01 03:16:03 volumio-office volumio[10490]: info: Plugin folders cleanup completed Nov 01 03:16:03 volumio-office volumio[10490]: info: ------------------------------------------- Nov 01 03:16:03 volumio-office volumio[10490]: info: ----- Core plugins startup ---- Nov 01 03:16:03 volumio-office volumio[10490]: info: ------------------------------------------- Nov 01 03:16:03 volumio-office volumio[10490]: info: Loading plugins from folder /volumio/app/plugins/ Nov 01 03:16:03 volumio-office volumio[10490]: info: Adding plugin upnp to MyMusic Plugins Nov 01 03:16:03 volumio-office volumio[10490]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 01 03:16:03 volumio-office volumio[10490]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 01 03:16:03 volumio-office volumio[10490]: info: Loading plugins from folder /data/plugins/ Nov 01 03:16:03 volumio-office volumio[10490]: info: Loading plugin "system"... Nov 01 03:16:03 volumio-office volumio[10490]: info: Loading plugin "appearance"... Nov 01 03:16:04 volumio-office volumio[10490]: info: Loading plugin "network"... Nov 01 03:16:04 volumio-office volumio[10490]: info: Refreshing Cached IP Addresses Nov 01 03:16:04 volumio-office sudo[10518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 01 03:16:04 volumio-office sudo[10518]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:04 volumio-office sudo[10520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 01 03:16:04 volumio-office sudo[10520]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:04 volumio-office sudo[10520]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:04 volumio-office sudo[10518]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:04 volumio-office volumio[10490]: info: Loading plugin "services"... Nov 01 03:16:04 volumio-office volumio[10490]: info: Loading plugin "alsa_controller"... Nov 01 03:16:04 volumio-office sudo[10535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 01 03:16:04 volumio-office sudo[10535]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:04 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:16:04 volumio-office volumio[10490]: info: Loading plugin "wizard"... Nov 01 03:16:04 volumio-office volumio[10490]: info: Loading plugin "networkfs"... Nov 01 03:16:04 volumio-office volumio[10490]: info: Starting Udev Watcher for removable devices Nov 01 03:16:04 volumio-office volumio[10490]: info: Ignoring mount for partition: boot Nov 01 03:16:04 volumio-office volumio[10490]: info: Ignoring mount for partition: volumio Nov 01 03:16:04 volumio-office volumio[10490]: info: Ignoring mount for partition: volumio_data Nov 01 03:16:04 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:16:04 volumio-office volumio[10490]: info: Loading plugin "volumio_command_line_client"... Nov 01 03:16:04 volumio-office volumio[10490]: info: Loading plugin "upnp"... Nov 01 03:16:04 volumio-office volumio[10490]: info: [1761981364529] Starting Upmpd Daemon Nov 01 03:16:04 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:16:04 volumio-office volumio[10490]: info: Loading plugin "my_music"... Nov 01 03:16:04 volumio-office volumio[10490]: info: Loading plugin "mpd"... Nov 01 03:16:04 volumio-office volumio[10490]: info: Loading plugin "upnp_browser"... Nov 01 03:16:05 volumio-office volumio[10490]: info: Loading plugin "alarm-clock"... Nov 01 03:16:05 volumio-office volumio[10490]: info: Plugin airplay_emulation is not enabled Nov 01 03:16:05 volumio-office volumio[10490]: info: Loading plugin "last_100"... Nov 01 03:16:05 volumio-office volumio[10490]: info: Loading plugin "webradio"... Nov 01 03:16:05 volumio-office volumio[10490]: info: Loading plugin "i2s_dacs"... Nov 01 03:16:05 volumio-office volumio[10490]: info: I2S DAC not set, start Auto-detection Nov 01 03:16:05 volumio-office volumio[10490]: info: Loading plugin "volumiodiscovery"... Nov 01 03:16:05 volumio-office volumio[10490]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 03:16:05 volumio-office volumio[10490]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:16:05 volumio-office volumio[10490]: *** WARNING *** For more information see Nov 01 03:16:05 volumio-office node[10490]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 03:16:05 volumio-office volumio[10490]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 03:16:05 volumio-office volumio[10490]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:16:05 volumio-office volumio[10490]: *** WARNING *** For more information see Nov 01 03:16:05 volumio-office node[10490]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:16:05 volumio-office node[10490]: *** WARNING *** For more information see Nov 01 03:16:05 volumio-office node[10490]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 03:16:05 volumio-office node[10490]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:16:05 volumio-office node[10490]: *** WARNING *** For more information see Nov 01 03:16:05 volumio-office volumio[10490]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 01 03:16:05 volumio-office volumio[10490]: info: Discovery: Started advertising with name: Volumio-Office Nov 01 03:16:05 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:16:05 volumio-office volumio[10490]: info: Loading plugin "squeezelite_mc"... Nov 01 03:16:06 volumio-office volumio[10490]: info: Loading plugin "outputs"... Nov 01 03:16:06 volumio-office volumio[10490]: info: Loading plugin "albumart"... Nov 01 03:16:06 volumio-office volumio[10490]: info: Plugin example_plugin is not enabled Nov 01 03:16:06 volumio-office volumio[10490]: info: Loading plugin "inputs"... Nov 01 03:16:06 volumio-office volumio[10490]: info: Loading plugin "updater_comm"... Nov 01 03:16:06 volumio-office volumio[10490]: info: Plugin mpdemulation is not enabled Nov 01 03:16:06 volumio-office volumio[10490]: info: Loading plugin "rest_api"... Nov 01 03:16:06 volumio-office volumio[10490]: info: Loading plugin "websocket"... Nov 01 03:16:06 volumio-office volumio[10490]: info: Starting Socket.io Server version 2.3.0 Nov 01 03:16:06 volumio-office volumio[10490]: info: Loading plugin "Systeminfo"... Nov 01 03:16:06 volumio-office volumio[10490]: Forking 3 albumart workers Nov 01 03:16:07 volumio-office volumio[10490]: info: Loading i18n strings for locale en Nov 01 03:16:07 volumio-office volumio[10490]: Updating browse sources language Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::initPlayerControls Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:16:07 volumio-office volumio[10490]: Express server listening on port 3000 Nov 01 03:16:07 volumio-office volumio[10490]: [Metrics] WebUI: 5s 385.25ms Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreStateMachine::resetVolumioState Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreStateMachine::getcurrentVolume Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreStateMachine::pushState Nov 01 03:16:07 volumio-office volumio[10490]: info: CorePlayQueue::getTrack 0 Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::volumioPushState Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:16:07 volumio-office volumio[10490]: info: Volumio Network Manager: Network status updated: 0 Nov 01 03:16:07 volumio-office volumio[10490]: info: Reloading queue from file Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreStateMachine::setRepeat null single undefined Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreStateMachine::pushState Nov 01 03:16:07 volumio-office volumio[10490]: info: CorePlayQueue::getTrack 0 Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::volumioPushState Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreStateMachine::setRandom null Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreStateMachine::pushState Nov 01 03:16:07 volumio-office volumio[10490]: info: CorePlayQueue::getTrack 0 Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::volumioPushState Nov 01 03:16:07 volumio-office volumio[10490]: info: Setting Device type: Raspberry PI Nov 01 03:16:07 volumio-office volumio[10490]: info: Completed loading Core Plugins Nov 01 03:16:07 volumio-office volumio[10490]: info: Preparing to generate the ALSA configuration file Nov 01 03:16:07 volumio-office volumio[10490]: Starting albumart workers Nov 01 03:16:07 volumio-office volumio[10490]: Starting albumart workers Nov 01 03:16:07 volumio-office volumio[10490]: info: Asound.conf file unchanged, so no further update is needed Nov 01 03:16:07 volumio-office volumio[10490]: info: Output device has changed, restarting MPD Nov 01 03:16:07 volumio-office volumio[10490]: Starting albumart workers Nov 01 03:16:07 volumio-office volumio[10490]: info: ___________ START PLUGINS ___________ Nov 01 03:16:07 volumio-office volumio[10490]: info: ControllerMpd::onStart: Initializing MPD Nov 01 03:16:07 volumio-office volumio[10490]: info: Creating MPD Configuration file Nov 01 03:16:07 volumio-office sudo[10586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 03:16:07 volumio-office sudo[10585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 03:16:07 volumio-office sudo[10586]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:07 volumio-office sudo[10585]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:07 volumio-office sudo[10585]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:16:07 volumio-office volumio[10490]: info: [1761981367801] CoreMusicLibrary::Adding element Media Servers Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:16:07 volumio-office systemd[1]: Stopping Music Player Daemon... Nov 01 03:16:07 volumio-office sudo[10589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 03:16:07 volumio-office sudo[10589]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:07 volumio-office sudo[10589]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:07 volumio-office sudo[10592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 03:16:07 volumio-office sudo[10592]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:16:07 volumio-office volumio[10490]: info: [1761981367844] CoreMusicLibrary::Adding element Last_100 Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:16:07 volumio-office volumio[10490]: info: [1761981367849] CoreMusicLibrary::Adding element Webradio Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:16:07 volumio-office volumio[10490]: info: Initializing BBC Radios Nov 01 03:16:07 volumio-office volumio[10490]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Nov 01 03:16:07 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:16:08 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:16:08 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:16:08 volumio-office volumio[10490]: info: [squeezelite_mc] Starting proxy server... Nov 01 03:16:08 volumio-office volumio[10490]: info: Volumio Calling Home Nov 01 03:16:08 volumio-office sudo[10535]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:08 volumio-office systemd[1]: mpd.service: Succeeded. Nov 01 03:16:08 volumio-office systemd[1]: Stopped Music Player Daemon. Nov 01 03:16:08 volumio-office volumio[10490]: info: [squeezelite_mc] Proxy server started on port 41563 Nov 01 03:16:08 volumio-office systemd[1]: Starting Music Player Daemon... Nov 01 03:16:08 volumio-office volumio[10490]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 03:16:08 volumio-office volumio[10490]: info: CoreStateMachine::pushState Nov 01 03:16:08 volumio-office volumio[10490]: info: CorePlayQueue::getTrack 0 Nov 01 03:16:08 volumio-office volumio[10490]: info: CoreCommandRouter::volumioPushState Nov 01 03:16:08 volumio-office volumio[10490]: info: [squeezelite_mc] Server discovery started Nov 01 03:16:08 volumio-office volumio[10490]: info: [squeezelite_mc] Player finder started Nov 01 03:16:08 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:08 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:08 volumio-office volumio[10490]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:16:08 volumio-office volumio[10490]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 01 03:16:08 volumio-office volumio[10490]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 03:16:08 volumio-office sudo[10608]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 03:16:08 volumio-office sudo[10608]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:08 volumio-office volumio[10490]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 01 03:16:08 volumio-office volumio[10490]: at doSend (dgram.js:692:16) Nov 01 03:16:08 volumio-office volumio[10490]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 01 03:16:08 volumio-office volumio[10490]: at afterDns (dgram.js:638:5) Nov 01 03:16:08 volumio-office volumio[10490]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 01 03:16:08 volumio-office volumio[10490]: errno: -101, Nov 01 03:16:08 volumio-office volumio[10490]: code: 'ENETUNREACH', Nov 01 03:16:08 volumio-office volumio[10490]: syscall: 'send', Nov 01 03:16:08 volumio-office volumio[10490]: address: '255.255.255.255', Nov 01 03:16:08 volumio-office volumio[10490]: port: 3483 Nov 01 03:16:08 volumio-office volumio[10490]: } Nov 01 03:16:08 volumio-office sudo[10608]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:08 volumio-office volumio[10490]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 03:16:09 volumio-office sudo[10647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-01 03:15 Nov 01 03:16:09 volumio-office sudo[10647]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:09 volumio-office sudo[10647]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:09 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Nov 01 03:16:09 volumio-office systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 01 03:16:09 volumio-office sudo[10592]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:09 volumio-office sudo[10586]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:09 volumio-office systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 01 03:16:09 volumio-office systemd[1]: Started dynamicswap service. Nov 01 03:16:09 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Nov 01 03:16:09 volumio-office systemd[1]: dynamicswap.service: Succeeded. Nov 01 03:16:09 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Nov 01 03:16:09 volumio-office systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 01 03:16:09 volumio-office systemd[1]: volumio.service: Scheduled restart job, restart counter is at 25. Nov 01 03:16:09 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Nov 01 03:16:09 volumio-office systemd[1]: Started dynamicswap service. Nov 01 03:16:09 volumio-office systemd[1]: Stopped Volumio Backend Module. Nov 01 03:16:09 volumio-office systemd[1]: Started Volumio Backend Module. Nov 01 03:16:09 volumio-office systemd[1]: dynamicswap.service: Succeeded. Nov 01 03:16:10 volumio-office volumio-remote-updater[507]: [2025-11-01 03:16:10] [info] asio async_connect error: system:111 (Connection refused) Nov 01 03:16:10 volumio-office volumio-remote-updater[507]: [2025-11-01 03:16:10] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 01 03:16:10 volumio-office volumio-remote-updater[507]: [2025-11-01 03:16:10] [error] handle_connect error: Underlying Transport Error Nov 01 03:16:11 volumio-office volumio[10661]: info: ------------------------------------------- Nov 01 03:16:11 volumio-office volumio[10661]: info: ----- Volumio3 ---- Nov 01 03:16:11 volumio-office volumio[10661]: info: ------------------------------------------- Nov 01 03:16:11 volumio-office volumio[10661]: info: ----- System startup ---- Nov 01 03:16:11 volumio-office volumio[10661]: info: ------------------------------------------- Nov 01 03:16:12 volumio-office mpd[10621]: Nov 01 03:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 01 03:16:12 volumio-office systemd[1]: Started Music Player Daemon. Nov 01 03:16:12 volumio-office volumio[10661]: info: MYVOLUMIO Environment detected Nov 01 03:16:12 volumio-office volumio[10661]: info: Plugin folders cleanup Nov 01 03:16:12 volumio-office volumio[10661]: info: Scanning into folder /volumio/app/plugins/ Nov 01 03:16:12 volumio-office volumio[10661]: info: Scanning category audio_interface Nov 01 03:16:12 volumio-office volumio[10661]: info: Scanning category miscellanea Nov 01 03:16:12 volumio-office volumio[10661]: info: Scanning category music_service Nov 01 03:16:12 volumio-office volumio[10661]: info: Scanning category plugins.json Nov 01 03:16:12 volumio-office volumio[10661]: info: Scanning category system_controller Nov 01 03:16:12 volumio-office volumio[10661]: info: Scanning category user_interface Nov 01 03:16:12 volumio-office volumio[10661]: info: Scanning into folder /data/plugins/ Nov 01 03:16:12 volumio-office volumio[10661]: info: Scanning category music_service Nov 01 03:16:12 volumio-office volumio[10661]: info: Scanning category user_interface Nov 01 03:16:12 volumio-office volumio[10661]: info: Plugin folders cleanup completed Nov 01 03:16:12 volumio-office volumio[10661]: info: ------------------------------------------- Nov 01 03:16:12 volumio-office volumio[10661]: info: ----- Core plugins startup ---- Nov 01 03:16:12 volumio-office volumio[10661]: info: ------------------------------------------- Nov 01 03:16:12 volumio-office volumio[10661]: info: Loading plugins from folder /volumio/app/plugins/ Nov 01 03:16:12 volumio-office volumio[10661]: info: Adding plugin upnp to MyMusic Plugins Nov 01 03:16:12 volumio-office volumio[10661]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 01 03:16:12 volumio-office volumio[10661]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 01 03:16:12 volumio-office volumio[10661]: info: Loading plugins from folder /data/plugins/ Nov 01 03:16:12 volumio-office volumio[10661]: info: Loading plugin "system"... Nov 01 03:16:12 volumio-office volumio[10661]: info: Loading plugin "appearance"... Nov 01 03:16:13 volumio-office volumio[10661]: info: Loading plugin "network"... Nov 01 03:16:13 volumio-office volumio[10661]: info: Refreshing Cached IP Addresses Nov 01 03:16:13 volumio-office sudo[10689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 01 03:16:13 volumio-office sudo[10689]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:13 volumio-office sudo[10691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 01 03:16:13 volumio-office sudo[10691]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:13 volumio-office sudo[10691]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:13 volumio-office sudo[10689]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:13 volumio-office volumio[10661]: info: Loading plugin "services"... Nov 01 03:16:13 volumio-office volumio[10661]: info: Loading plugin "alsa_controller"... Nov 01 03:16:13 volumio-office sudo[10702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 01 03:16:13 volumio-office sudo[10702]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:13 volumio-office sudo[10702]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:13 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:16:13 volumio-office volumio[10661]: info: Loading plugin "wizard"... Nov 01 03:16:13 volumio-office volumio[10661]: info: Loading plugin "networkfs"... Nov 01 03:16:13 volumio-office volumio[10661]: info: Starting Udev Watcher for removable devices Nov 01 03:16:13 volumio-office volumio[10661]: info: Ignoring mount for partition: boot Nov 01 03:16:13 volumio-office volumio[10661]: info: Ignoring mount for partition: volumio Nov 01 03:16:13 volumio-office volumio[10661]: info: Ignoring mount for partition: volumio_data Nov 01 03:16:13 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:16:13 volumio-office volumio[10661]: info: Loading plugin "volumio_command_line_client"... Nov 01 03:16:13 volumio-office volumio[10661]: info: Loading plugin "upnp"... Nov 01 03:16:13 volumio-office volumio[10661]: info: [1761981373456] Starting Upmpd Daemon Nov 01 03:16:13 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:16:13 volumio-office volumio[10661]: info: Loading plugin "my_music"... Nov 01 03:16:13 volumio-office volumio[10661]: info: Loading plugin "mpd"... Nov 01 03:16:13 volumio-office volumio[10661]: info: Loading plugin "upnp_browser"... Nov 01 03:16:14 volumio-office volumio[10661]: info: Loading plugin "alarm-clock"... Nov 01 03:16:14 volumio-office volumio[10661]: info: Plugin airplay_emulation is not enabled Nov 01 03:16:14 volumio-office volumio[10661]: info: Loading plugin "last_100"... Nov 01 03:16:14 volumio-office volumio[10661]: info: Loading plugin "webradio"... Nov 01 03:16:14 volumio-office volumio[10661]: info: Loading plugin "i2s_dacs"... Nov 01 03:16:14 volumio-office volumio[10661]: info: I2S DAC not set, start Auto-detection Nov 01 03:16:14 volumio-office volumio[10661]: info: Loading plugin "volumiodiscovery"... Nov 01 03:16:14 volumio-office volumio[10661]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 03:16:14 volumio-office volumio[10661]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:16:14 volumio-office node[10661]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 03:16:14 volumio-office volumio[10661]: *** WARNING *** For more information see Nov 01 03:16:14 volumio-office volumio[10661]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 03:16:14 volumio-office volumio[10661]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:16:14 volumio-office volumio[10661]: *** WARNING *** For more information see Nov 01 03:16:14 volumio-office node[10661]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:16:14 volumio-office node[10661]: *** WARNING *** For more information see Nov 01 03:16:14 volumio-office node[10661]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 03:16:14 volumio-office node[10661]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:16:14 volumio-office node[10661]: *** WARNING *** For more information see Nov 01 03:16:14 volumio-office volumio[10661]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 01 03:16:14 volumio-office volumio[10661]: info: Discovery: Started advertising with name: Volumio-Office Nov 01 03:16:14 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:16:14 volumio-office volumio[10661]: info: Loading plugin "squeezelite_mc"... Nov 01 03:16:15 volumio-office volumio[10661]: info: Loading plugin "outputs"... Nov 01 03:16:15 volumio-office volumio[10661]: info: Loading plugin "albumart"... Nov 01 03:16:15 volumio-office volumio[10661]: info: Plugin example_plugin is not enabled Nov 01 03:16:15 volumio-office volumio[10661]: info: Loading plugin "inputs"... Nov 01 03:16:15 volumio-office volumio[10661]: info: Loading plugin "updater_comm"... Nov 01 03:16:15 volumio-office volumio[10661]: info: Plugin mpdemulation is not enabled Nov 01 03:16:15 volumio-office volumio[10661]: info: Loading plugin "rest_api"... Nov 01 03:16:15 volumio-office volumio[10661]: info: Loading plugin "websocket"... Nov 01 03:16:15 volumio-office volumio[10661]: info: Starting Socket.io Server version 2.3.0 Nov 01 03:16:15 volumio-office volumio[10661]: info: Loading plugin "Systeminfo"... Nov 01 03:16:15 volumio-office volumio[10661]: Forking 3 albumart workers Nov 01 03:16:16 volumio-office volumio[10661]: info: Loading i18n strings for locale en Nov 01 03:16:16 volumio-office volumio[10661]: Updating browse sources language Nov 01 03:16:16 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:16:16 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Nov 01 03:16:16 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:16:16 volumio-office volumio[10661]: info: CoreCommandRouter::initPlayerControls Nov 01 03:16:16 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:16 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:16 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:16 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:16 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:16 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:16 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:16 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:16 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Nov 01 03:16:16 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:16:16 volumio-office volumio[10661]: Express server listening on port 3000 Nov 01 03:16:16 volumio-office volumio[10661]: [Metrics] WebUI: 5s 469.59ms Nov 01 03:16:16 volumio-office volumio[10661]: info: CoreStateMachine::resetVolumioState Nov 01 03:16:16 volumio-office volumio[10661]: info: CoreStateMachine::getcurrentVolume Nov 01 03:16:16 volumio-office volumio[10661]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 03:16:16 volumio-office volumio[10661]: info: CoreStateMachine::pushState Nov 01 03:16:16 volumio-office volumio[10661]: info: CorePlayQueue::getTrack 0 Nov 01 03:16:16 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 03:16:16 volumio-office volumio[10661]: info: CoreCommandRouter::volumioPushState Nov 01 03:16:16 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:16:16 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Nov 01 03:16:16 volumio-office volumio[10661]: info: Volumio Network Manager: Network status updated: 0 Nov 01 03:16:16 volumio-office volumio[10661]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 01 03:16:16 volumio-office volumio[10661]: wlan0 Interface doesn't support scanning : Device or resource busy Nov 01 03:16:16 volumio-office volumio[10661]: info: Cannot use regular scanning, forcing with ap-force Nov 01 03:16:16 volumio-office sudo[10751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 01 03:16:16 volumio-office sudo[10751]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:16 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Nov 01 03:16:16 volumio-office volumio[10661]: Starting albumart workers Nov 01 03:16:16 volumio-office volumio[10661]: Starting albumart workers Nov 01 03:16:16 volumio-office volumio[10661]: Starting albumart workers Nov 01 03:16:19 volumio-office wpa_supplicant[825]: wlan0: Failed to initiate sched scan Nov 01 03:16:20 volumio-office volumio-remote-updater[507]: [2025-11-01 03:16:20] [connect] Successful connection Nov 01 03:16:21 volumio-office sudo[10751]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:21 volumio-office volumio-remote-updater[507]: [2025-11-01 03:16:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1761981380 101 Nov 01 03:16:21 volumio-office volumio[10661]: 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 Nov 01 03:16:22 volumio-office volumio[10661]: info: Reloading queue from file Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreStateMachine::setRepeat null single undefined Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreStateMachine::pushState Nov 01 03:16:22 volumio-office volumio[10661]: info: CorePlayQueue::getTrack 0 Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreCommandRouter::volumioPushState Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreStateMachine::setRandom null Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreStateMachine::pushState Nov 01 03:16:22 volumio-office volumio[10661]: info: CorePlayQueue::getTrack 0 Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreCommandRouter::volumioPushState Nov 01 03:16:22 volumio-office volumio[10661]: info: Setting Device type: Raspberry PI Nov 01 03:16:22 volumio-office volumio[10661]: info: Completed loading Core Plugins Nov 01 03:16:22 volumio-office volumio[10661]: info: Preparing to generate the ALSA configuration file Nov 01 03:16:22 volumio-office volumio[10661]: info: Asound.conf file unchanged, so no further update is needed Nov 01 03:16:22 volumio-office volumio[10661]: info: Output device has changed, restarting MPD Nov 01 03:16:22 volumio-office sudo[10786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 03:16:22 volumio-office sudo[10786]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:22 volumio-office volumio[10661]: info: ___________ START PLUGINS ___________ Nov 01 03:16:22 volumio-office sudo[10786]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:22 volumio-office volumio[10661]: info: ControllerMpd::onStart: Initializing MPD Nov 01 03:16:22 volumio-office volumio[10661]: info: Creating MPD Configuration file Nov 01 03:16:22 volumio-office sudo[10788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 03:16:22 volumio-office sudo[10788]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:22 volumio-office systemd[1]: Stopping Music Player Daemon... Nov 01 03:16:22 volumio-office sudo[10792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 03:16:22 volumio-office sudo[10792]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:16:22 volumio-office volumio[10661]: info: [1761981382156] CoreMusicLibrary::Adding element Media Servers Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:16:22 volumio-office sudo[10794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 03:16:22 volumio-office sudo[10794]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:22 volumio-office sudo[10792]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:22 volumio-office systemd[1]: mpd.service: Succeeded. Nov 01 03:16:22 volumio-office systemd[1]: Stopped Music Player Daemon. Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:16:22 volumio-office volumio[10661]: info: [1761981382190] CoreMusicLibrary::Adding element Last_100 Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:16:22 volumio-office volumio[10661]: info: [1761981382193] CoreMusicLibrary::Adding element Webradio Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:16:22 volumio-office volumio[10661]: info: Initializing BBC Radios Nov 01 03:16:22 volumio-office volumio[10661]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:16:22 volumio-office systemd[1]: Starting Music Player Daemon... Nov 01 03:16:22 volumio-office systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 01 03:16:22 volumio-office systemd[1]: mpd.service: Succeeded. Nov 01 03:16:22 volumio-office systemd[1]: Stopped Music Player Daemon. Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:16:22 volumio-office systemd[1]: Starting Music Player Daemon... Nov 01 03:16:22 volumio-office volumio[10661]: info: [squeezelite_mc] Starting proxy server... Nov 01 03:16:22 volumio-office volumio[10661]: info: Volumio Calling Home Nov 01 03:16:22 volumio-office sudo[10808]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 03:16:22 volumio-office sudo[10808]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:22 volumio-office sudo[10808]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:22 volumio-office volumio[10661]: info: [squeezelite_mc] Proxy server started on port 40487 Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreStateMachine::pushState Nov 01 03:16:22 volumio-office volumio[10661]: info: CorePlayQueue::getTrack 0 Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreCommandRouter::volumioPushState Nov 01 03:16:22 volumio-office volumio[10661]: info: [squeezelite_mc] Server discovery started Nov 01 03:16:22 volumio-office volumio[10661]: info: [squeezelite_mc] Player finder started Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:22 volumio-office volumio[10661]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:16:22 volumio-office volumio[10661]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 01 03:16:22 volumio-office volumio[10661]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 03:16:22 volumio-office volumio[10661]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 01 03:16:22 volumio-office volumio[10661]: at doSend (dgram.js:692:16) Nov 01 03:16:22 volumio-office volumio[10661]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 01 03:16:22 volumio-office volumio[10661]: at afterDns (dgram.js:638:5) Nov 01 03:16:22 volumio-office volumio[10661]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 01 03:16:22 volumio-office volumio[10661]: errno: -101, Nov 01 03:16:22 volumio-office volumio[10661]: code: 'ENETUNREACH', Nov 01 03:16:22 volumio-office volumio[10661]: syscall: 'send', Nov 01 03:16:22 volumio-office volumio[10661]: address: '255.255.255.255', Nov 01 03:16:22 volumio-office volumio[10661]: port: 3483 Nov 01 03:16:22 volumio-office volumio[10661]: } Nov 01 03:16:22 volumio-office volumio[10661]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 03:16:23 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Nov 01 03:16:23 volumio-office sudo[10825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-01 03:15 Nov 01 03:16:23 volumio-office sudo[10825]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:23 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Nov 01 03:16:23 volumio-office sudo[10825]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:23 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Nov 01 03:16:23 volumio-office volumio-remote-updater[507]: [2025-11-01 03:16:23] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 01 03:16:23 volumio-office volumio-remote-updater[507]: [2025-11-01 03:16:23] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 01 03:16:23 volumio-office systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 01 03:16:23 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Nov 01 03:16:23 volumio-office sudo[10794]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:23 volumio-office sudo[10788]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:23 volumio-office systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 01 03:16:23 volumio-office systemd[1]: Started dynamicswap service. Nov 01 03:16:23 volumio-office systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 01 03:16:23 volumio-office systemd[1]: volumio.service: Scheduled restart job, restart counter is at 26. Nov 01 03:16:23 volumio-office systemd[1]: dynamicswap.service: Succeeded. Nov 01 03:16:23 volumio-office systemd[1]: Stopped Volumio Backend Module. Nov 01 03:16:23 volumio-office systemd[1]: Started Volumio Backend Module. Nov 01 03:16:23 volumio-office mpd[10813]: Nov 01 03:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 01 03:16:23 volumio-office systemd[1]: Started Music Player Daemon. Nov 01 03:16:25 volumio-office volumio[10853]: info: ------------------------------------------- Nov 01 03:16:25 volumio-office volumio[10853]: info: ----- Volumio3 ---- Nov 01 03:16:25 volumio-office volumio[10853]: info: ------------------------------------------- Nov 01 03:16:25 volumio-office volumio[10853]: info: ----- System startup ---- Nov 01 03:16:25 volumio-office volumio[10853]: info: ------------------------------------------- Nov 01 03:16:25 volumio-office volumio[10853]: info: MYVOLUMIO Environment detected Nov 01 03:16:25 volumio-office volumio[10853]: info: Plugin folders cleanup Nov 01 03:16:25 volumio-office volumio[10853]: info: Scanning into folder /volumio/app/plugins/ Nov 01 03:16:25 volumio-office volumio[10853]: info: Scanning category audio_interface Nov 01 03:16:25 volumio-office volumio[10853]: info: Scanning category miscellanea Nov 01 03:16:25 volumio-office volumio[10853]: info: Scanning category music_service Nov 01 03:16:25 volumio-office volumio[10853]: info: Scanning category plugins.json Nov 01 03:16:25 volumio-office volumio[10853]: info: Scanning category system_controller Nov 01 03:16:25 volumio-office volumio[10853]: info: Scanning category user_interface Nov 01 03:16:25 volumio-office volumio[10853]: info: Scanning into folder /data/plugins/ Nov 01 03:16:25 volumio-office volumio[10853]: info: Scanning category music_service Nov 01 03:16:25 volumio-office volumio[10853]: info: Scanning category user_interface Nov 01 03:16:25 volumio-office volumio[10853]: info: Plugin folders cleanup completed Nov 01 03:16:25 volumio-office volumio[10853]: info: ------------------------------------------- Nov 01 03:16:25 volumio-office volumio[10853]: info: ----- Core plugins startup ---- Nov 01 03:16:25 volumio-office volumio[10853]: info: ------------------------------------------- Nov 01 03:16:25 volumio-office volumio[10853]: info: Loading plugins from folder /volumio/app/plugins/ Nov 01 03:16:25 volumio-office volumio[10853]: info: Adding plugin upnp to MyMusic Plugins Nov 01 03:16:25 volumio-office volumio[10853]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 01 03:16:25 volumio-office volumio[10853]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 01 03:16:25 volumio-office volumio[10853]: info: Loading plugins from folder /data/plugins/ Nov 01 03:16:25 volumio-office volumio[10853]: info: Loading plugin "system"... Nov 01 03:16:26 volumio-office volumio[10853]: info: Loading plugin "appearance"... Nov 01 03:16:26 volumio-office volumio[10853]: info: Loading plugin "network"... Nov 01 03:16:26 volumio-office volumio[10853]: info: Refreshing Cached IP Addresses Nov 01 03:16:26 volumio-office sudo[10876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 01 03:16:26 volumio-office sudo[10876]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:26 volumio-office sudo[10878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 01 03:16:26 volumio-office sudo[10878]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:27 volumio-office sudo[10878]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:27 volumio-office volumio[10853]: info: Loading plugin "services"... Nov 01 03:16:27 volumio-office volumio[10853]: info: Loading plugin "alsa_controller"... Nov 01 03:16:27 volumio-office sudo[10876]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:27 volumio-office sudo[10889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 01 03:16:27 volumio-office sudo[10889]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:27 volumio-office sudo[10889]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:27 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:16:27 volumio-office volumio[10853]: info: Loading plugin "wizard"... Nov 01 03:16:27 volumio-office volumio[10853]: info: Loading plugin "networkfs"... Nov 01 03:16:27 volumio-office volumio[10853]: info: Starting Udev Watcher for removable devices Nov 01 03:16:27 volumio-office volumio[10853]: info: Ignoring mount for partition: boot Nov 01 03:16:27 volumio-office volumio[10853]: info: Ignoring mount for partition: volumio Nov 01 03:16:27 volumio-office volumio[10853]: info: Ignoring mount for partition: volumio_data Nov 01 03:16:27 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:16:27 volumio-office volumio[10853]: info: Loading plugin "volumio_command_line_client"... Nov 01 03:16:27 volumio-office volumio[10853]: info: Loading plugin "upnp"... Nov 01 03:16:27 volumio-office volumio[10853]: info: [1761981387155] Starting Upmpd Daemon Nov 01 03:16:27 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:16:27 volumio-office volumio[10853]: info: Loading plugin "my_music"... Nov 01 03:16:27 volumio-office volumio[10853]: info: Loading plugin "mpd"... Nov 01 03:16:27 volumio-office volumio[10853]: info: Loading plugin "upnp_browser"... Nov 01 03:16:27 volumio-office volumio[10853]: info: Loading plugin "alarm-clock"... Nov 01 03:16:27 volumio-office volumio[10853]: info: Plugin airplay_emulation is not enabled Nov 01 03:16:27 volumio-office volumio[10853]: info: Loading plugin "last_100"... Nov 01 03:16:27 volumio-office volumio[10853]: info: Loading plugin "webradio"... Nov 01 03:16:28 volumio-office volumio[10853]: info: Loading plugin "i2s_dacs"... Nov 01 03:16:28 volumio-office volumio[10853]: info: I2S DAC not set, start Auto-detection Nov 01 03:16:28 volumio-office volumio[10853]: info: Loading plugin "volumiodiscovery"... Nov 01 03:16:28 volumio-office volumio[10853]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 03:16:28 volumio-office volumio[10853]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:16:28 volumio-office node[10853]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 03:16:28 volumio-office volumio[10853]: *** WARNING *** For more information see Nov 01 03:16:28 volumio-office volumio[10853]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 03:16:28 volumio-office volumio[10853]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:16:28 volumio-office volumio[10853]: *** WARNING *** For more information see Nov 01 03:16:28 volumio-office node[10853]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:16:28 volumio-office node[10853]: *** WARNING *** For more information see Nov 01 03:16:28 volumio-office node[10853]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 03:16:28 volumio-office node[10853]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:16:28 volumio-office node[10853]: *** WARNING *** For more information see Nov 01 03:16:28 volumio-office volumio[10853]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 01 03:16:28 volumio-office volumio[10853]: info: Discovery: Started advertising with name: Volumio-Office Nov 01 03:16:28 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:16:28 volumio-office volumio[10853]: info: Loading plugin "squeezelite_mc"... Nov 01 03:16:28 volumio-office volumio-remote-updater[507]: [2025-11-01 03:16:28] [connect] Successful connection Nov 01 03:16:28 volumio-office volumio[10853]: info: Loading plugin "outputs"... Nov 01 03:16:28 volumio-office volumio[10853]: info: Loading plugin "albumart"... Nov 01 03:16:28 volumio-office volumio[10853]: info: Plugin example_plugin is not enabled Nov 01 03:16:28 volumio-office volumio[10853]: info: Loading plugin "inputs"... Nov 01 03:16:28 volumio-office volumio[10853]: info: Loading plugin "updater_comm"... Nov 01 03:16:29 volumio-office volumio[10853]: info: Plugin mpdemulation is not enabled Nov 01 03:16:29 volumio-office volumio[10853]: info: Loading plugin "rest_api"... Nov 01 03:16:29 volumio-office volumio[10853]: info: Loading plugin "websocket"... Nov 01 03:16:29 volumio-office volumio[10853]: info: Starting Socket.io Server version 2.3.0 Nov 01 03:16:29 volumio-office volumio[10853]: info: Loading plugin "Systeminfo"... Nov 01 03:16:29 volumio-office volumio[10853]: Forking 3 albumart workers Nov 01 03:16:29 volumio-office volumio[10853]: info: Loading i18n strings for locale en Nov 01 03:16:29 volumio-office volumio[10853]: Updating browse sources language Nov 01 03:16:29 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:16:29 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Nov 01 03:16:30 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:16:30 volumio-office volumio[10853]: info: CoreCommandRouter::initPlayerControls Nov 01 03:16:30 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:30 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:30 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:30 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:30 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:30 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:30 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:30 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:30 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:16:30 volumio-office volumio[10853]: Express server listening on port 3000 Nov 01 03:16:30 volumio-office volumio[10853]: [Metrics] WebUI: 5s 396.51ms Nov 01 03:16:30 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Nov 01 03:16:30 volumio-office volumio[10853]: info: CoreStateMachine::resetVolumioState Nov 01 03:16:30 volumio-office volumio[10853]: info: CoreStateMachine::getcurrentVolume Nov 01 03:16:30 volumio-office volumio[10853]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 03:16:30 volumio-office volumio[10853]: info: CoreStateMachine::pushState Nov 01 03:16:30 volumio-office volumio[10853]: info: CorePlayQueue::getTrack 0 Nov 01 03:16:30 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 03:16:30 volumio-office volumio[10853]: info: CoreCommandRouter::volumioPushState Nov 01 03:16:30 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:16:30 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Nov 01 03:16:30 volumio-office volumio[10853]: info: Volumio Network Manager: Network status updated: 0 Nov 01 03:16:30 volumio-office volumio[10853]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 01 03:16:30 volumio-office volumio[10853]: wlan0 Interface doesn't support scanning : Device or resource busy Nov 01 03:16:30 volumio-office volumio[10853]: info: Cannot use regular scanning, forcing with ap-force Nov 01 03:16:30 volumio-office sudo[10937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 01 03:16:30 volumio-office sudo[10937]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:30 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Nov 01 03:16:30 volumio-office volumio[10853]: Starting albumart workers Nov 01 03:16:30 volumio-office volumio[10853]: Starting albumart workers Nov 01 03:16:30 volumio-office volumio[10853]: Starting albumart workers Nov 01 03:16:32 volumio-office wpa_supplicant[825]: wlan0: Failed to initiate sched scan Nov 01 03:16:35 volumio-office sudo[10937]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:35 volumio-office volumio[10853]: info: Reloading queue from file Nov 01 03:16:35 volumio-office volumio[10853]: info: CoreStateMachine::setRepeat null single undefined Nov 01 03:16:35 volumio-office volumio[10853]: info: CoreStateMachine::pushState Nov 01 03:16:35 volumio-office volumio[10853]: info: CorePlayQueue::getTrack 0 Nov 01 03:16:35 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 03:16:35 volumio-office volumio[10853]: info: CoreCommandRouter::volumioPushState Nov 01 03:16:35 volumio-office volumio[10853]: info: CoreStateMachine::setRandom null Nov 01 03:16:35 volumio-office volumio[10853]: info: CoreStateMachine::pushState Nov 01 03:16:35 volumio-office volumio[10853]: info: CorePlayQueue::getTrack 0 Nov 01 03:16:35 volumio-office volumio[10853]: info: CoreCommandRouter::volumioPushState Nov 01 03:16:35 volumio-office volumio[10853]: info: Setting Device type: Raspberry PI Nov 01 03:16:35 volumio-office volumio[10853]: info: Completed loading Core Plugins Nov 01 03:16:35 volumio-office volumio[10853]: info: Preparing to generate the ALSA configuration file Nov 01 03:16:35 volumio-office volumio[10853]: info: Asound.conf file unchanged, so no further update is needed Nov 01 03:16:35 volumio-office volumio[10853]: info: Output device has changed, restarting MPD Nov 01 03:16:35 volumio-office sudo[10968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 03:16:35 volumio-office sudo[10968]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:35 volumio-office volumio[10853]: info: ___________ START PLUGINS ___________ Nov 01 03:16:35 volumio-office sudo[10968]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:35 volumio-office volumio[10853]: info: ControllerMpd::onStart: Initializing MPD Nov 01 03:16:35 volumio-office volumio[10853]: info: Creating MPD Configuration file Nov 01 03:16:35 volumio-office sudo[10970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 03:16:35 volumio-office sudo[10970]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:35 volumio-office systemd[1]: Stopping Music Player Daemon... Nov 01 03:16:35 volumio-office sudo[10974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 03:16:35 volumio-office sudo[10974]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:35 volumio-office sudo[10974]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:35 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:16:35 volumio-office volumio[10853]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:16:35 volumio-office volumio[10853]: info: [1761981395793] CoreMusicLibrary::Adding element Media Servers Nov 01 03:16:35 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:16:35 volumio-office systemd[1]: mpd.service: Succeeded. Nov 01 03:16:35 volumio-office systemd[1]: Stopped Music Player Daemon. Nov 01 03:16:35 volumio-office sudo[10977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 03:16:35 volumio-office sudo[10977]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:35 volumio-office systemd[1]: Starting Music Player Daemon... Nov 01 03:16:35 volumio-office volumio[10853]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:16:35 volumio-office volumio[10853]: info: [1761981395820] CoreMusicLibrary::Adding element Last_100 Nov 01 03:16:35 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:16:35 volumio-office volumio[10853]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:16:35 volumio-office volumio[10853]: info: [1761981395823] CoreMusicLibrary::Adding element Webradio Nov 01 03:16:35 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:16:35 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:16:35 volumio-office volumio[10853]: info: Initializing BBC Radios Nov 01 03:16:35 volumio-office volumio[10853]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Nov 01 03:16:35 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:16:35 volumio-office systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 01 03:16:35 volumio-office systemd[1]: mpd.service: Succeeded. Nov 01 03:16:35 volumio-office systemd[1]: Stopped Music Player Daemon. Nov 01 03:16:35 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:16:35 volumio-office systemd[1]: Starting Music Player Daemon... Nov 01 03:16:35 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:16:35 volumio-office volumio[10853]: info: [squeezelite_mc] Starting proxy server... Nov 01 03:16:35 volumio-office volumio[10853]: info: Volumio Calling Home Nov 01 03:16:36 volumio-office sudo[10990]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 03:16:36 volumio-office sudo[10990]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:36 volumio-office sudo[10990]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:36 volumio-office volumio[10853]: info: [squeezelite_mc] Proxy server started on port 36169 Nov 01 03:16:36 volumio-office volumio[10853]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 03:16:36 volumio-office volumio[10853]: info: CoreStateMachine::pushState Nov 01 03:16:36 volumio-office volumio[10853]: info: CorePlayQueue::getTrack 0 Nov 01 03:16:36 volumio-office volumio[10853]: info: CoreCommandRouter::volumioPushState Nov 01 03:16:36 volumio-office volumio[10853]: info: [squeezelite_mc] Server discovery started Nov 01 03:16:36 volumio-office volumio[10853]: info: [squeezelite_mc] Player finder started Nov 01 03:16:36 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:36 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:36 volumio-office volumio[10853]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:16:36 volumio-office volumio[10853]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 01 03:16:36 volumio-office volumio[10853]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 03:16:36 volumio-office volumio[10853]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 01 03:16:36 volumio-office volumio[10853]: at doSend (dgram.js:692:16) Nov 01 03:16:36 volumio-office volumio[10853]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 01 03:16:36 volumio-office volumio[10853]: at afterDns (dgram.js:638:5) Nov 01 03:16:36 volumio-office volumio[10853]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 01 03:16:36 volumio-office volumio[10853]: errno: -101, Nov 01 03:16:36 volumio-office volumio[10853]: code: 'ENETUNREACH', Nov 01 03:16:36 volumio-office volumio[10853]: syscall: 'send', Nov 01 03:16:36 volumio-office volumio[10853]: address: '255.255.255.255', Nov 01 03:16:36 volumio-office volumio[10853]: port: 3483 Nov 01 03:16:36 volumio-office volumio[10853]: } Nov 01 03:16:36 volumio-office volumio[10853]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 03:16:36 volumio-office sudo[11007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-01 03:15 Nov 01 03:16:36 volumio-office sudo[11007]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:36 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Nov 01 03:16:36 volumio-office sudo[11007]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:36 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Nov 01 03:16:37 volumio-office systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 01 03:16:37 volumio-office sudo[10977]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:37 volumio-office sudo[10970]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:37 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Nov 01 03:16:37 volumio-office systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 01 03:16:37 volumio-office systemd[1]: Started dynamicswap service. Nov 01 03:16:37 volumio-office systemd[1]: dynamicswap.service: Succeeded. Nov 01 03:16:37 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Nov 01 03:16:37 volumio-office systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 01 03:16:37 volumio-office systemd[1]: volumio.service: Scheduled restart job, restart counter is at 27. Nov 01 03:16:37 volumio-office mpd[10995]: Nov 01 03:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 01 03:16:37 volumio-office systemd[1]: Started dynamicswap service. Nov 01 03:16:37 volumio-office systemd[1]: Stopped Volumio Backend Module. Nov 01 03:16:37 volumio-office systemd[1]: Started Volumio Backend Module. Nov 01 03:16:37 volumio-office systemd[1]: Started Music Player Daemon. Nov 01 03:16:37 volumio-office systemd[1]: dynamicswap.service: Succeeded. Nov 01 03:16:39 volumio-office volumio[11023]: info: ------------------------------------------- Nov 01 03:16:39 volumio-office volumio[11023]: info: ----- Volumio3 ---- Nov 01 03:16:39 volumio-office volumio[11023]: info: ------------------------------------------- Nov 01 03:16:39 volumio-office volumio[11023]: info: ----- System startup ---- Nov 01 03:16:39 volumio-office volumio[11023]: info: ------------------------------------------- Nov 01 03:16:39 volumio-office nmbd[639]: [2025/11/01 03:16:39.118515, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) Nov 01 03:16:39 volumio-office nmbd[639]: reload_interfaces: No subnets to listen to. Waiting.. Nov 01 03:16:39 volumio-office volumio[11023]: info: MYVOLUMIO Environment detected Nov 01 03:16:39 volumio-office volumio[11023]: info: Plugin folders cleanup Nov 01 03:16:39 volumio-office volumio[11023]: info: Scanning into folder /volumio/app/plugins/ Nov 01 03:16:39 volumio-office volumio[11023]: info: Scanning category audio_interface Nov 01 03:16:39 volumio-office volumio[11023]: info: Scanning category miscellanea Nov 01 03:16:39 volumio-office volumio[11023]: info: Scanning category music_service Nov 01 03:16:39 volumio-office volumio[11023]: info: Scanning category plugins.json Nov 01 03:16:39 volumio-office volumio[11023]: info: Scanning category system_controller Nov 01 03:16:39 volumio-office volumio[11023]: info: Scanning category user_interface Nov 01 03:16:39 volumio-office volumio[11023]: info: Scanning into folder /data/plugins/ Nov 01 03:16:39 volumio-office volumio[11023]: info: Scanning category music_service Nov 01 03:16:39 volumio-office volumio[11023]: info: Scanning category user_interface Nov 01 03:16:39 volumio-office volumio[11023]: info: Plugin folders cleanup completed Nov 01 03:16:39 volumio-office volumio[11023]: info: ------------------------------------------- Nov 01 03:16:39 volumio-office volumio[11023]: info: ----- Core plugins startup ---- Nov 01 03:16:39 volumio-office volumio[11023]: info: ------------------------------------------- Nov 01 03:16:39 volumio-office volumio[11023]: info: Loading plugins from folder /volumio/app/plugins/ Nov 01 03:16:39 volumio-office volumio[11023]: info: Adding plugin upnp to MyMusic Plugins Nov 01 03:16:39 volumio-office volumio[11023]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 01 03:16:39 volumio-office volumio[11023]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 01 03:16:39 volumio-office volumio[11023]: info: Loading plugins from folder /data/plugins/ Nov 01 03:16:39 volumio-office volumio[11023]: info: Loading plugin "system"... Nov 01 03:16:39 volumio-office volumio[11023]: info: Loading plugin "appearance"... Nov 01 03:16:40 volumio-office volumio[11023]: info: Loading plugin "network"... Nov 01 03:16:40 volumio-office volumio[11023]: info: Refreshing Cached IP Addresses Nov 01 03:16:40 volumio-office sudo[11091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 01 03:16:40 volumio-office sudo[11091]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:40 volumio-office sudo[11093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 01 03:16:40 volumio-office sudo[11093]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:40 volumio-office volumio[11023]: info: Loading plugin "services"... Nov 01 03:16:40 volumio-office sudo[11093]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:40 volumio-office sudo[11091]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:40 volumio-office volumio-remote-updater[507]: [2025-11-01 03:16:40] [connect] Successful connection Nov 01 03:16:40 volumio-office volumio[11023]: info: Loading plugin "alsa_controller"... Nov 01 03:16:40 volumio-office sudo[11104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 01 03:16:40 volumio-office sudo[11104]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:40 volumio-office sudo[11104]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:40 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:16:41 volumio-office volumio[11023]: info: Loading plugin "wizard"... Nov 01 03:16:41 volumio-office volumio[11023]: info: Loading plugin "networkfs"... Nov 01 03:16:41 volumio-office volumio[11023]: info: Starting Udev Watcher for removable devices Nov 01 03:16:41 volumio-office volumio[11023]: info: Ignoring mount for partition: boot Nov 01 03:16:41 volumio-office volumio[11023]: info: Ignoring mount for partition: volumio Nov 01 03:16:41 volumio-office volumio[11023]: info: Ignoring mount for partition: volumio_data Nov 01 03:16:41 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:16:41 volumio-office volumio[11023]: info: Loading plugin "volumio_command_line_client"... Nov 01 03:16:41 volumio-office volumio[11023]: info: Loading plugin "upnp"... Nov 01 03:16:41 volumio-office volumio[11023]: info: [1761981401069] Starting Upmpd Daemon Nov 01 03:16:41 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:16:41 volumio-office volumio[11023]: info: Loading plugin "my_music"... Nov 01 03:16:41 volumio-office volumio[11023]: info: Loading plugin "mpd"... Nov 01 03:16:41 volumio-office volumio[11023]: info: Loading plugin "upnp_browser"... Nov 01 03:16:41 volumio-office volumio[11023]: info: Loading plugin "alarm-clock"... Nov 01 03:16:41 volumio-office volumio[11023]: info: Plugin airplay_emulation is not enabled Nov 01 03:16:41 volumio-office volumio[11023]: info: Loading plugin "last_100"... Nov 01 03:16:41 volumio-office volumio[11023]: info: Loading plugin "webradio"... Nov 01 03:16:41 volumio-office volumio[11023]: info: Loading plugin "i2s_dacs"... Nov 01 03:16:41 volumio-office volumio[11023]: info: I2S DAC not set, start Auto-detection Nov 01 03:16:41 volumio-office volumio[11023]: info: Loading plugin "volumiodiscovery"... Nov 01 03:16:42 volumio-office volumio[11023]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 03:16:42 volumio-office volumio[11023]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:16:42 volumio-office volumio[11023]: *** WARNING *** For more information see Nov 01 03:16:42 volumio-office node[11023]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 03:16:42 volumio-office volumio[11023]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 03:16:42 volumio-office volumio[11023]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:16:42 volumio-office volumio[11023]: *** WARNING *** For more information see Nov 01 03:16:42 volumio-office node[11023]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:16:42 volumio-office node[11023]: *** WARNING *** For more information see Nov 01 03:16:42 volumio-office node[11023]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 03:16:42 volumio-office node[11023]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:16:42 volumio-office node[11023]: *** WARNING *** For more information see Nov 01 03:16:42 volumio-office volumio[11023]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 01 03:16:42 volumio-office volumio[11023]: info: Discovery: Started advertising with name: Volumio-Office Nov 01 03:16:42 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:16:42 volumio-office volumio[11023]: info: Loading plugin "squeezelite_mc"... Nov 01 03:16:42 volumio-office volumio[11023]: info: Loading plugin "outputs"... Nov 01 03:16:42 volumio-office volumio[11023]: info: Loading plugin "albumart"... Nov 01 03:16:42 volumio-office volumio[11023]: info: Plugin example_plugin is not enabled Nov 01 03:16:42 volumio-office volumio[11023]: info: Loading plugin "inputs"... Nov 01 03:16:42 volumio-office volumio[11023]: info: Loading plugin "updater_comm"... Nov 01 03:16:43 volumio-office volumio[11023]: info: Plugin mpdemulation is not enabled Nov 01 03:16:43 volumio-office volumio[11023]: info: Loading plugin "rest_api"... Nov 01 03:16:43 volumio-office volumio[11023]: info: Loading plugin "websocket"... Nov 01 03:16:43 volumio-office volumio[11023]: info: Starting Socket.io Server version 2.3.0 Nov 01 03:16:43 volumio-office volumio[11023]: info: Loading plugin "Systeminfo"... Nov 01 03:16:43 volumio-office volumio[11023]: Forking 3 albumart workers Nov 01 03:16:43 volumio-office volumio[11023]: info: Loading i18n strings for locale en Nov 01 03:16:43 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Nov 01 03:16:43 volumio-office volumio[11023]: Updating browse sources language Nov 01 03:16:43 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:16:43 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Nov 01 03:16:43 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Nov 01 03:16:43 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:16:43 volumio-office volumio[11023]: info: CoreCommandRouter::initPlayerControls Nov 01 03:16:43 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:43 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:43 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:43 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:43 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:43 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:43 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:43 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:43 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:16:43 volumio-office volumio[11023]: Express server listening on port 3000 Nov 01 03:16:43 volumio-office volumio[11023]: [Metrics] WebUI: 5s 442.52ms Nov 01 03:16:43 volumio-office volumio[11023]: info: CoreStateMachine::resetVolumioState Nov 01 03:16:43 volumio-office volumio[11023]: info: CoreStateMachine::getcurrentVolume Nov 01 03:16:43 volumio-office volumio[11023]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 03:16:43 volumio-office volumio[11023]: info: CoreStateMachine::pushState Nov 01 03:16:44 volumio-office volumio[11023]: info: CorePlayQueue::getTrack 0 Nov 01 03:16:44 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 03:16:44 volumio-office volumio[11023]: info: CoreCommandRouter::volumioPushState Nov 01 03:16:44 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:16:44 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Nov 01 03:16:44 volumio-office volumio[11023]: info: Volumio Network Manager: Network status updated: 0 Nov 01 03:16:44 volumio-office volumio[11023]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 01 03:16:44 volumio-office volumio[11023]: wlan0 Interface doesn't support scanning : Device or resource busy Nov 01 03:16:44 volumio-office volumio[11023]: info: Cannot use regular scanning, forcing with ap-force Nov 01 03:16:44 volumio-office sudo[11152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 01 03:16:44 volumio-office sudo[11152]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:44 volumio-office volumio[11023]: Starting albumart workers Nov 01 03:16:44 volumio-office volumio[11023]: Starting albumart workers Nov 01 03:16:44 volumio-office volumio[11023]: Starting albumart workers Nov 01 03:16:46 volumio-office wpa_supplicant[825]: wlan0: Failed to initiate sched scan Nov 01 03:16:49 volumio-office sudo[11152]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:49 volumio-office volumio[11023]: info: Reloading queue from file Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreStateMachine::setRepeat null single undefined Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreStateMachine::pushState Nov 01 03:16:49 volumio-office volumio[11023]: info: CorePlayQueue::getTrack 0 Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreCommandRouter::volumioPushState Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreStateMachine::setRandom null Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreStateMachine::pushState Nov 01 03:16:49 volumio-office volumio[11023]: info: CorePlayQueue::getTrack 0 Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreCommandRouter::volumioPushState Nov 01 03:16:49 volumio-office volumio[11023]: info: Setting Device type: Raspberry PI Nov 01 03:16:49 volumio-office volumio[11023]: info: Completed loading Core Plugins Nov 01 03:16:49 volumio-office volumio[11023]: info: Preparing to generate the ALSA configuration file Nov 01 03:16:49 volumio-office volumio[11023]: info: Asound.conf file unchanged, so no further update is needed Nov 01 03:16:49 volumio-office volumio[11023]: info: Output device has changed, restarting MPD Nov 01 03:16:49 volumio-office sudo[11183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 03:16:49 volumio-office sudo[11183]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:49 volumio-office volumio[11023]: info: ___________ START PLUGINS ___________ Nov 01 03:16:49 volumio-office sudo[11183]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:49 volumio-office volumio[11023]: info: ControllerMpd::onStart: Initializing MPD Nov 01 03:16:49 volumio-office volumio[11023]: info: Creating MPD Configuration file Nov 01 03:16:49 volumio-office sudo[11185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 03:16:49 volumio-office sudo[11185]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:49 volumio-office systemd[1]: Stopping Music Player Daemon... Nov 01 03:16:49 volumio-office sudo[11189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 03:16:49 volumio-office sudo[11189]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:49 volumio-office sudo[11189]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:16:49 volumio-office volumio[11023]: info: [1761981409520] CoreMusicLibrary::Adding element Media Servers Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:16:49 volumio-office sudo[11191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 03:16:49 volumio-office sudo[11191]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:49 volumio-office systemd[1]: mpd.service: Succeeded. Nov 01 03:16:49 volumio-office systemd[1]: Stopped Music Player Daemon. Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:16:49 volumio-office volumio[11023]: info: [1761981409548] CoreMusicLibrary::Adding element Last_100 Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:16:49 volumio-office volumio[11023]: info: [1761981409551] CoreMusicLibrary::Adding element Webradio Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:16:49 volumio-office volumio[11023]: info: Initializing BBC Radios Nov 01 03:16:49 volumio-office volumio[11023]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:16:49 volumio-office systemd[1]: Starting Music Player Daemon... Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:16:49 volumio-office systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 01 03:16:49 volumio-office systemd[1]: mpd.service: Succeeded. Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:16:49 volumio-office systemd[1]: Stopped Music Player Daemon. Nov 01 03:16:49 volumio-office systemd[1]: Starting Music Player Daemon... Nov 01 03:16:49 volumio-office volumio[11023]: info: [squeezelite_mc] Starting proxy server... Nov 01 03:16:49 volumio-office volumio[11023]: info: Volumio Calling Home Nov 01 03:16:49 volumio-office sudo[11205]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 03:16:49 volumio-office sudo[11205]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:49 volumio-office sudo[11205]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:49 volumio-office volumio[11023]: info: [squeezelite_mc] Proxy server started on port 45177 Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreStateMachine::pushState Nov 01 03:16:49 volumio-office volumio[11023]: info: CorePlayQueue::getTrack 0 Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreCommandRouter::volumioPushState Nov 01 03:16:49 volumio-office volumio[11023]: info: [squeezelite_mc] Server discovery started Nov 01 03:16:49 volumio-office volumio[11023]: info: [squeezelite_mc] Player finder started Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:49 volumio-office volumio[11023]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:16:49 volumio-office volumio[11023]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 01 03:16:49 volumio-office volumio[11023]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 03:16:49 volumio-office volumio[11023]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 01 03:16:49 volumio-office volumio[11023]: at doSend (dgram.js:692:16) Nov 01 03:16:49 volumio-office volumio[11023]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 01 03:16:49 volumio-office volumio[11023]: at afterDns (dgram.js:638:5) Nov 01 03:16:49 volumio-office volumio[11023]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 01 03:16:49 volumio-office volumio[11023]: errno: -101, Nov 01 03:16:49 volumio-office volumio[11023]: code: 'ENETUNREACH', Nov 01 03:16:49 volumio-office volumio[11023]: syscall: 'send', Nov 01 03:16:49 volumio-office volumio[11023]: address: '255.255.255.255', Nov 01 03:16:49 volumio-office volumio[11023]: port: 3483 Nov 01 03:16:49 volumio-office volumio[11023]: } Nov 01 03:16:49 volumio-office volumio[11023]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 03:16:50 volumio-office sudo[11222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-01 03:15 Nov 01 03:16:50 volumio-office sudo[11222]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:50 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Nov 01 03:16:50 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Nov 01 03:16:50 volumio-office sudo[11222]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:50 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Nov 01 03:16:50 volumio-office systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 01 03:16:50 volumio-office sudo[11185]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:50 volumio-office sudo[11191]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:50 volumio-office systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 01 03:16:50 volumio-office systemd[1]: Started dynamicswap service. Nov 01 03:16:50 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Nov 01 03:16:50 volumio-office systemd[1]: dynamicswap.service: Succeeded. Nov 01 03:16:51 volumio-office systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 01 03:16:51 volumio-office systemd[1]: volumio.service: Scheduled restart job, restart counter is at 28. Nov 01 03:16:51 volumio-office systemd[1]: Started dynamicswap service. Nov 01 03:16:51 volumio-office systemd[1]: Stopped Volumio Backend Module. Nov 01 03:16:51 volumio-office systemd[1]: Started Volumio Backend Module. Nov 01 03:16:51 volumio-office systemd[1]: dynamicswap.service: Succeeded. Nov 01 03:16:51 volumio-office mpd[11210]: Nov 01 03:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 01 03:16:51 volumio-office systemd[1]: Started Music Player Daemon. Nov 01 03:16:52 volumio-office volumio[11236]: info: ------------------------------------------- Nov 01 03:16:52 volumio-office volumio[11236]: info: ----- Volumio3 ---- Nov 01 03:16:52 volumio-office volumio[11236]: info: ------------------------------------------- Nov 01 03:16:52 volumio-office volumio[11236]: info: ----- System startup ---- Nov 01 03:16:52 volumio-office volumio[11236]: info: ------------------------------------------- Nov 01 03:16:53 volumio-office volumio[11236]: info: MYVOLUMIO Environment detected Nov 01 03:16:53 volumio-office volumio[11236]: info: Plugin folders cleanup Nov 01 03:16:53 volumio-office volumio[11236]: info: Scanning into folder /volumio/app/plugins/ Nov 01 03:16:53 volumio-office volumio[11236]: info: Scanning category audio_interface Nov 01 03:16:53 volumio-office volumio[11236]: info: Scanning category miscellanea Nov 01 03:16:53 volumio-office volumio[11236]: info: Scanning category music_service Nov 01 03:16:53 volumio-office volumio[11236]: info: Scanning category plugins.json Nov 01 03:16:53 volumio-office volumio[11236]: info: Scanning category system_controller Nov 01 03:16:53 volumio-office volumio[11236]: info: Scanning category user_interface Nov 01 03:16:53 volumio-office volumio[11236]: info: Scanning into folder /data/plugins/ Nov 01 03:16:53 volumio-office volumio[11236]: info: Scanning category music_service Nov 01 03:16:53 volumio-office volumio[11236]: info: Scanning category user_interface Nov 01 03:16:53 volumio-office volumio[11236]: info: Plugin folders cleanup completed Nov 01 03:16:53 volumio-office volumio[11236]: info: ------------------------------------------- Nov 01 03:16:53 volumio-office volumio[11236]: info: ----- Core plugins startup ---- Nov 01 03:16:53 volumio-office volumio[11236]: info: ------------------------------------------- Nov 01 03:16:53 volumio-office volumio[11236]: info: Loading plugins from folder /volumio/app/plugins/ Nov 01 03:16:53 volumio-office volumio[11236]: info: Adding plugin upnp to MyMusic Plugins Nov 01 03:16:53 volumio-office volumio[11236]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 01 03:16:53 volumio-office volumio[11236]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 01 03:16:53 volumio-office volumio[11236]: info: Loading plugins from folder /data/plugins/ Nov 01 03:16:53 volumio-office volumio[11236]: info: Loading plugin "system"... Nov 01 03:16:53 volumio-office volumio[11236]: info: Loading plugin "appearance"... Nov 01 03:16:54 volumio-office volumio[11236]: info: Loading plugin "network"... Nov 01 03:16:54 volumio-office volumio[11236]: info: Refreshing Cached IP Addresses Nov 01 03:16:54 volumio-office sudo[11264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 01 03:16:54 volumio-office sudo[11264]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:54 volumio-office sudo[11266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 01 03:16:54 volumio-office sudo[11266]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:54 volumio-office sudo[11266]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:54 volumio-office sudo[11264]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:54 volumio-office volumio[11236]: info: Loading plugin "services"... Nov 01 03:16:54 volumio-office volumio[11236]: info: Loading plugin "alsa_controller"... Nov 01 03:16:54 volumio-office sudo[11281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 01 03:16:54 volumio-office sudo[11281]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:54 volumio-office sudo[11281]: pam_unix(sudo:session): session closed for user root Nov 01 03:16:54 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:16:54 volumio-office volumio[11236]: info: Loading plugin "wizard"... Nov 01 03:16:54 volumio-office volumio[11236]: info: Loading plugin "networkfs"... Nov 01 03:16:54 volumio-office volumio[11236]: info: Starting Udev Watcher for removable devices Nov 01 03:16:54 volumio-office volumio[11236]: info: Ignoring mount for partition: boot Nov 01 03:16:54 volumio-office volumio[11236]: info: Ignoring mount for partition: volumio Nov 01 03:16:54 volumio-office volumio[11236]: info: Ignoring mount for partition: volumio_data Nov 01 03:16:54 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:16:54 volumio-office volumio[11236]: info: Loading plugin "volumio_command_line_client"... Nov 01 03:16:54 volumio-office volumio[11236]: info: Loading plugin "upnp"... Nov 01 03:16:54 volumio-office volumio[11236]: info: [1761981414721] Starting Upmpd Daemon Nov 01 03:16:54 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:16:54 volumio-office volumio[11236]: info: Loading plugin "my_music"... Nov 01 03:16:54 volumio-office volumio[11236]: info: Loading plugin "mpd"... Nov 01 03:16:55 volumio-office volumio[11236]: info: Loading plugin "upnp_browser"... Nov 01 03:16:55 volumio-office volumio[11236]: info: Loading plugin "alarm-clock"... Nov 01 03:16:55 volumio-office volumio[11236]: info: Plugin airplay_emulation is not enabled Nov 01 03:16:55 volumio-office volumio[11236]: info: Loading plugin "last_100"... Nov 01 03:16:55 volumio-office volumio[11236]: info: Loading plugin "webradio"... Nov 01 03:16:55 volumio-office volumio[11236]: info: Loading plugin "i2s_dacs"... Nov 01 03:16:55 volumio-office volumio[11236]: info: I2S DAC not set, start Auto-detection Nov 01 03:16:55 volumio-office volumio[11236]: info: Loading plugin "volumiodiscovery"... Nov 01 03:16:55 volumio-office volumio[11236]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 03:16:55 volumio-office volumio[11236]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:16:55 volumio-office volumio[11236]: *** WARNING *** For more information see Nov 01 03:16:55 volumio-office node[11236]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 03:16:55 volumio-office volumio[11236]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 03:16:55 volumio-office volumio[11236]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:16:55 volumio-office volumio[11236]: *** WARNING *** For more information see Nov 01 03:16:55 volumio-office node[11236]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:16:55 volumio-office node[11236]: *** WARNING *** For more information see Nov 01 03:16:55 volumio-office node[11236]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 03:16:55 volumio-office node[11236]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:16:55 volumio-office node[11236]: *** WARNING *** For more information see Nov 01 03:16:55 volumio-office volumio[11236]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 01 03:16:55 volumio-office volumio[11236]: info: Discovery: Started advertising with name: Volumio-Office Nov 01 03:16:55 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:16:55 volumio-office volumio[11236]: info: Loading plugin "squeezelite_mc"... Nov 01 03:16:55 volumio-office volumio-remote-updater[507]: [2025-11-01 03:16:55] [connect] Successful connection Nov 01 03:16:56 volumio-office volumio[11236]: info: Loading plugin "outputs"... Nov 01 03:16:56 volumio-office volumio[11236]: info: Loading plugin "albumart"... Nov 01 03:16:56 volumio-office volumio[11236]: info: Plugin example_plugin is not enabled Nov 01 03:16:56 volumio-office volumio[11236]: info: Loading plugin "inputs"... Nov 01 03:16:56 volumio-office volumio[11236]: info: Loading plugin "updater_comm"... Nov 01 03:16:56 volumio-office volumio[11236]: info: Plugin mpdemulation is not enabled Nov 01 03:16:56 volumio-office volumio[11236]: info: Loading plugin "rest_api"... Nov 01 03:16:56 volumio-office volumio[11236]: info: Loading plugin "websocket"... Nov 01 03:16:56 volumio-office volumio[11236]: info: Starting Socket.io Server version 2.3.0 Nov 01 03:16:56 volumio-office volumio[11236]: info: Loading plugin "Systeminfo"... Nov 01 03:16:56 volumio-office volumio[11236]: Forking 3 albumart workers Nov 01 03:16:57 volumio-office volumio[11236]: info: Loading i18n strings for locale en Nov 01 03:16:57 volumio-office volumio[11236]: Updating browse sources language Nov 01 03:16:57 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:16:57 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Nov 01 03:16:57 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Nov 01 03:16:57 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:16:57 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Nov 01 03:16:57 volumio-office volumio[11236]: info: CoreCommandRouter::initPlayerControls Nov 01 03:16:57 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:57 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:57 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:57 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:57 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:57 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:57 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:57 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:16:57 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:16:57 volumio-office volumio[11236]: Express server listening on port 3000 Nov 01 03:16:57 volumio-office volumio[11236]: [Metrics] WebUI: 5s 446.94ms Nov 01 03:16:57 volumio-office volumio[11236]: info: CoreStateMachine::resetVolumioState Nov 01 03:16:57 volumio-office volumio[11236]: info: CoreStateMachine::getcurrentVolume Nov 01 03:16:57 volumio-office volumio[11236]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 03:16:57 volumio-office volumio[11236]: info: CoreStateMachine::pushState Nov 01 03:16:57 volumio-office volumio[11236]: info: CorePlayQueue::getTrack 0 Nov 01 03:16:57 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 03:16:57 volumio-office volumio[11236]: info: CoreCommandRouter::volumioPushState Nov 01 03:16:57 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Nov 01 03:16:57 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:16:57 volumio-office volumio[11236]: info: Volumio Network Manager: Network status updated: 0 Nov 01 03:16:57 volumio-office volumio[11236]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 01 03:16:57 volumio-office volumio[11236]: wlan0 Interface doesn't support scanning : Device or resource busy Nov 01 03:16:57 volumio-office volumio[11236]: info: Cannot use regular scanning, forcing with ap-force Nov 01 03:16:57 volumio-office volumio[11236]: Starting albumart workers Nov 01 03:16:57 volumio-office sudo[11325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 01 03:16:57 volumio-office sudo[11325]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:16:57 volumio-office volumio[11236]: Starting albumart workers Nov 01 03:16:57 volumio-office volumio[11236]: Starting albumart workers Nov 01 03:17:00 volumio-office wpa_supplicant[825]: wlan0: Failed to initiate sched scan Nov 01 03:17:02 volumio-office sudo[11325]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:03 volumio-office volumio[11236]: info: Reloading queue from file Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreStateMachine::setRepeat null single undefined Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreStateMachine::pushState Nov 01 03:17:03 volumio-office volumio[11236]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreStateMachine::setRandom null Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreStateMachine::pushState Nov 01 03:17:03 volumio-office volumio[11236]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:03 volumio-office volumio[11236]: info: Setting Device type: Raspberry PI Nov 01 03:17:03 volumio-office volumio[11236]: info: Completed loading Core Plugins Nov 01 03:17:03 volumio-office volumio[11236]: info: Preparing to generate the ALSA configuration file Nov 01 03:17:03 volumio-office volumio[11236]: info: Asound.conf file unchanged, so no further update is needed Nov 01 03:17:03 volumio-office volumio[11236]: info: Output device has changed, restarting MPD Nov 01 03:17:03 volumio-office sudo[11359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 03:17:03 volumio-office sudo[11359]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:03 volumio-office volumio[11236]: info: ___________ START PLUGINS ___________ Nov 01 03:17:03 volumio-office sudo[11359]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:03 volumio-office volumio[11236]: info: ControllerMpd::onStart: Initializing MPD Nov 01 03:17:03 volumio-office volumio[11236]: info: Creating MPD Configuration file Nov 01 03:17:03 volumio-office sudo[11361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 03:17:03 volumio-office sudo[11361]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:03 volumio-office systemd[1]: Stopping Music Player Daemon... Nov 01 03:17:03 volumio-office sudo[11365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:17:03 volumio-office volumio[11236]: info: [1761981423228] CoreMusicLibrary::Adding element Media Servers Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:03 volumio-office sudo[11365]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:03 volumio-office sudo[11367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 03:17:03 volumio-office sudo[11367]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:17:03 volumio-office volumio[11236]: info: [1761981423251] CoreMusicLibrary::Adding element Last_100 Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:17:03 volumio-office volumio[11236]: info: [1761981423256] CoreMusicLibrary::Adding element Webradio Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:03 volumio-office sudo[11365]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:17:03 volumio-office systemd[1]: mpd.service: Succeeded. Nov 01 03:17:03 volumio-office systemd[1]: Stopped Music Player Daemon. Nov 01 03:17:03 volumio-office volumio[11236]: info: Initializing BBC Radios Nov 01 03:17:03 volumio-office volumio[11236]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:17:03 volumio-office systemd[1]: Starting Music Player Daemon... Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:17:03 volumio-office volumio[11236]: info: [squeezelite_mc] Starting proxy server... Nov 01 03:17:03 volumio-office volumio[11236]: info: Volumio Calling Home Nov 01 03:17:03 volumio-office sudo[11370]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 03:17:03 volumio-office sudo[11370]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:03 volumio-office sudo[11370]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:03 volumio-office volumio[11236]: info: [squeezelite_mc] Proxy server started on port 43739 Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreStateMachine::pushState Nov 01 03:17:03 volumio-office volumio[11236]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:03 volumio-office volumio[11236]: info: [squeezelite_mc] Server discovery started Nov 01 03:17:03 volumio-office volumio[11236]: info: [squeezelite_mc] Player finder started Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:03 volumio-office volumio[11236]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:03 volumio-office volumio[11236]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 01 03:17:03 volumio-office volumio[11236]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 03:17:03 volumio-office volumio[11236]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 01 03:17:03 volumio-office volumio[11236]: at doSend (dgram.js:692:16) Nov 01 03:17:03 volumio-office volumio[11236]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 01 03:17:03 volumio-office volumio[11236]: at afterDns (dgram.js:638:5) Nov 01 03:17:03 volumio-office volumio[11236]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 01 03:17:03 volumio-office volumio[11236]: errno: -101, Nov 01 03:17:03 volumio-office volumio[11236]: code: 'ENETUNREACH', Nov 01 03:17:03 volumio-office volumio[11236]: syscall: 'send', Nov 01 03:17:03 volumio-office volumio[11236]: address: '255.255.255.255', Nov 01 03:17:03 volumio-office volumio[11236]: port: 3483 Nov 01 03:17:03 volumio-office volumio[11236]: } Nov 01 03:17:03 volumio-office volumio[11236]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 03:17:04 volumio-office sudo[11397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-01 03:16 Nov 01 03:17:04 volumio-office sudo[11397]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:04 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Nov 01 03:17:04 volumio-office sudo[11397]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:04 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Nov 01 03:17:04 volumio-office systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 01 03:17:04 volumio-office sudo[11361]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:04 volumio-office sudo[11367]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:04 volumio-office systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 01 03:17:04 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Nov 01 03:17:04 volumio-office systemd[1]: Started dynamicswap service. Nov 01 03:17:04 volumio-office systemd[1]: dynamicswap.service: Succeeded. Nov 01 03:17:04 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Nov 01 03:17:04 volumio-office systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 01 03:17:04 volumio-office systemd[1]: volumio.service: Scheduled restart job, restart counter is at 29. Nov 01 03:17:04 volumio-office systemd[1]: Stopped Volumio Backend Module. Nov 01 03:17:04 volumio-office systemd[1]: Started Volumio Backend Module. Nov 01 03:17:04 volumio-office systemd[1]: Started dynamicswap service. Nov 01 03:17:04 volumio-office systemd[1]: dynamicswap.service: Succeeded. Nov 01 03:17:04 volumio-office mpd[11385]: Nov 01 03:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 01 03:17:04 volumio-office systemd[1]: Started Music Player Daemon. Nov 01 03:17:06 volumio-office volumio[11410]: info: ------------------------------------------- Nov 01 03:17:06 volumio-office volumio[11410]: info: ----- Volumio3 ---- Nov 01 03:17:06 volumio-office volumio[11410]: info: ------------------------------------------- Nov 01 03:17:06 volumio-office volumio[11410]: info: ----- System startup ---- Nov 01 03:17:06 volumio-office volumio[11410]: info: ------------------------------------------- Nov 01 03:17:06 volumio-office volumio[11410]: info: MYVOLUMIO Environment detected Nov 01 03:17:06 volumio-office volumio[11410]: info: Plugin folders cleanup Nov 01 03:17:06 volumio-office volumio[11410]: info: Scanning into folder /volumio/app/plugins/ Nov 01 03:17:06 volumio-office volumio[11410]: info: Scanning category audio_interface Nov 01 03:17:06 volumio-office volumio[11410]: info: Scanning category miscellanea Nov 01 03:17:06 volumio-office volumio[11410]: info: Scanning category music_service Nov 01 03:17:07 volumio-office volumio[11410]: info: Scanning category plugins.json Nov 01 03:17:07 volumio-office volumio[11410]: info: Scanning category system_controller Nov 01 03:17:07 volumio-office volumio[11410]: info: Scanning category user_interface Nov 01 03:17:07 volumio-office volumio[11410]: info: Scanning into folder /data/plugins/ Nov 01 03:17:07 volumio-office volumio[11410]: info: Scanning category music_service Nov 01 03:17:07 volumio-office volumio[11410]: info: Scanning category user_interface Nov 01 03:17:07 volumio-office volumio[11410]: info: Plugin folders cleanup completed Nov 01 03:17:07 volumio-office volumio[11410]: info: ------------------------------------------- Nov 01 03:17:07 volumio-office volumio[11410]: info: ----- Core plugins startup ---- Nov 01 03:17:07 volumio-office volumio[11410]: info: ------------------------------------------- Nov 01 03:17:07 volumio-office volumio[11410]: info: Loading plugins from folder /volumio/app/plugins/ Nov 01 03:17:07 volumio-office volumio[11410]: info: Adding plugin upnp to MyMusic Plugins Nov 01 03:17:07 volumio-office volumio[11410]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 01 03:17:07 volumio-office volumio[11410]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 01 03:17:07 volumio-office volumio[11410]: info: Loading plugins from folder /data/plugins/ Nov 01 03:17:07 volumio-office volumio[11410]: info: Loading plugin "system"... Nov 01 03:17:07 volumio-office volumio[11410]: info: Loading plugin "appearance"... Nov 01 03:17:07 volumio-office volumio[11410]: info: Loading plugin "network"... Nov 01 03:17:08 volumio-office volumio[11410]: info: Refreshing Cached IP Addresses Nov 01 03:17:08 volumio-office sudo[11439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 01 03:17:08 volumio-office sudo[11439]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:08 volumio-office sudo[11441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 01 03:17:08 volumio-office sudo[11441]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:08 volumio-office sudo[11441]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:08 volumio-office sudo[11439]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:08 volumio-office volumio[11410]: info: Loading plugin "services"... Nov 01 03:17:08 volumio-office volumio[11410]: info: Loading plugin "alsa_controller"... Nov 01 03:17:08 volumio-office sudo[11456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 01 03:17:08 volumio-office sudo[11456]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:08 volumio-office sudo[11456]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:08 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:17:08 volumio-office volumio[11410]: info: Loading plugin "wizard"... Nov 01 03:17:08 volumio-office volumio[11410]: info: Loading plugin "networkfs"... Nov 01 03:17:08 volumio-office volumio[11410]: info: Starting Udev Watcher for removable devices Nov 01 03:17:08 volumio-office volumio[11410]: info: Ignoring mount for partition: boot Nov 01 03:17:08 volumio-office volumio[11410]: info: Ignoring mount for partition: volumio Nov 01 03:17:08 volumio-office volumio[11410]: info: Ignoring mount for partition: volumio_data Nov 01 03:17:08 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:17:08 volumio-office volumio[11410]: info: Loading plugin "volumio_command_line_client"... Nov 01 03:17:08 volumio-office volumio[11410]: info: Loading plugin "upnp"... Nov 01 03:17:08 volumio-office volumio[11410]: info: [1761981428210] Starting Upmpd Daemon Nov 01 03:17:08 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:17:08 volumio-office volumio[11410]: info: Loading plugin "my_music"... Nov 01 03:17:08 volumio-office volumio[11410]: info: Loading plugin "mpd"... Nov 01 03:17:08 volumio-office volumio[11410]: info: Loading plugin "upnp_browser"... Nov 01 03:17:08 volumio-office volumio[11410]: info: Loading plugin "alarm-clock"... Nov 01 03:17:09 volumio-office volumio[11410]: info: Plugin airplay_emulation is not enabled Nov 01 03:17:09 volumio-office volumio[11410]: info: Loading plugin "last_100"... Nov 01 03:17:09 volumio-office volumio[11410]: info: Loading plugin "webradio"... Nov 01 03:17:09 volumio-office volumio[11410]: info: Loading plugin "i2s_dacs"... Nov 01 03:17:09 volumio-office volumio[11410]: info: I2S DAC not set, start Auto-detection Nov 01 03:17:09 volumio-office volumio[11410]: info: Loading plugin "volumiodiscovery"... Nov 01 03:17:09 volumio-office volumio[11410]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 03:17:09 volumio-office volumio[11410]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:17:09 volumio-office volumio[11410]: *** WARNING *** For more information see Nov 01 03:17:09 volumio-office node[11410]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 03:17:09 volumio-office volumio[11410]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 03:17:09 volumio-office volumio[11410]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:17:09 volumio-office volumio[11410]: *** WARNING *** For more information see Nov 01 03:17:09 volumio-office node[11410]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:17:09 volumio-office node[11410]: *** WARNING *** For more information see Nov 01 03:17:09 volumio-office node[11410]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 03:17:09 volumio-office node[11410]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:17:09 volumio-office node[11410]: *** WARNING *** For more information see Nov 01 03:17:09 volumio-office volumio[11410]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 01 03:17:09 volumio-office volumio[11410]: info: Discovery: Started advertising with name: Volumio-Office Nov 01 03:17:09 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:17:09 volumio-office volumio[11410]: info: Loading plugin "squeezelite_mc"... Nov 01 03:17:09 volumio-office volumio[11410]: info: Loading plugin "outputs"... Nov 01 03:17:09 volumio-office volumio[11410]: info: Loading plugin "albumart"... Nov 01 03:17:10 volumio-office volumio[11410]: info: Plugin example_plugin is not enabled Nov 01 03:17:10 volumio-office volumio[11410]: info: Loading plugin "inputs"... Nov 01 03:17:10 volumio-office volumio[11410]: info: Loading plugin "updater_comm"... Nov 01 03:17:10 volumio-office volumio[11410]: info: Plugin mpdemulation is not enabled Nov 01 03:17:10 volumio-office volumio[11410]: info: Loading plugin "rest_api"... Nov 01 03:17:10 volumio-office volumio[11410]: info: Loading plugin "websocket"... Nov 01 03:17:10 volumio-office volumio[11410]: info: Starting Socket.io Server version 2.3.0 Nov 01 03:17:10 volumio-office volumio[11410]: info: Loading plugin "Systeminfo"... Nov 01 03:17:10 volumio-office volumio[11410]: Forking 3 albumart workers Nov 01 03:17:10 volumio-office volumio[11410]: info: Loading i18n strings for locale en Nov 01 03:17:10 volumio-office volumio[11410]: Updating browse sources language Nov 01 03:17:10 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:10 volumio-office volumio-remote-updater[507]: [2025-11-01 03:17:10] [connect] Successful connection Nov 01 03:17:10 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:10 volumio-office volumio[11410]: info: CoreCommandRouter::initPlayerControls Nov 01 03:17:10 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:10 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:10 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:10 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:10 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:10 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:10 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:10 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:10 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:11 volumio-office volumio[11410]: Express server listening on port 3000 Nov 01 03:17:11 volumio-office volumio[11410]: [Metrics] WebUI: 5s 291.01ms Nov 01 03:17:11 volumio-office volumio[11410]: info: CoreStateMachine::resetVolumioState Nov 01 03:17:11 volumio-office volumio[11410]: info: CoreStateMachine::getcurrentVolume Nov 01 03:17:11 volumio-office volumio[11410]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 03:17:11 volumio-office volumio[11410]: info: CoreStateMachine::pushState Nov 01 03:17:11 volumio-office volumio[11410]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:11 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 03:17:11 volumio-office volumio[11410]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:11 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:17:11 volumio-office volumio[11410]: info: Volumio Network Manager: Network status updated: 0 Nov 01 03:17:11 volumio-office volumio[11410]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 01 03:17:11 volumio-office volumio[11410]: wlan0 Interface doesn't support scanning : Device or resource busy Nov 01 03:17:11 volumio-office volumio[11410]: info: Cannot use regular scanning, forcing with ap-force Nov 01 03:17:11 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Nov 01 03:17:11 volumio-office sudo[11500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 01 03:17:11 volumio-office sudo[11500]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:11 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Nov 01 03:17:11 volumio-office volumio[11410]: Starting albumart workers Nov 01 03:17:11 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Nov 01 03:17:11 volumio-office volumio[11410]: Starting albumart workers Nov 01 03:17:11 volumio-office volumio[11410]: Starting albumart workers Nov 01 03:17:11 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Nov 01 03:17:16 volumio-office sudo[11500]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:16 volumio-office volumio[11410]: info: Reloading queue from file Nov 01 03:17:16 volumio-office volumio[11410]: info: CoreStateMachine::setRepeat null single undefined Nov 01 03:17:16 volumio-office volumio[11410]: info: CoreStateMachine::pushState Nov 01 03:17:16 volumio-office volumio[11410]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:16 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 03:17:16 volumio-office volumio[11410]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:16 volumio-office volumio[11410]: info: CoreStateMachine::setRandom null Nov 01 03:17:16 volumio-office volumio[11410]: info: CoreStateMachine::pushState Nov 01 03:17:16 volumio-office volumio[11410]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:16 volumio-office volumio[11410]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:16 volumio-office volumio[11410]: info: Setting Device type: Raspberry PI Nov 01 03:17:16 volumio-office volumio[11410]: info: Completed loading Core Plugins Nov 01 03:17:16 volumio-office volumio[11410]: info: Preparing to generate the ALSA configuration file Nov 01 03:17:16 volumio-office volumio[11410]: info: Asound.conf file unchanged, so no further update is needed Nov 01 03:17:16 volumio-office volumio[11410]: info: Output device has changed, restarting MPD Nov 01 03:17:16 volumio-office sudo[11531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 03:17:16 volumio-office sudo[11531]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:16 volumio-office volumio[11410]: info: ___________ START PLUGINS ___________ Nov 01 03:17:16 volumio-office sudo[11531]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:16 volumio-office volumio[11410]: info: ControllerMpd::onStart: Initializing MPD Nov 01 03:17:16 volumio-office volumio[11410]: info: Creating MPD Configuration file Nov 01 03:17:16 volumio-office sudo[11533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 03:17:16 volumio-office sudo[11533]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:16 volumio-office systemd[1]: Stopping Music Player Daemon... Nov 01 03:17:16 volumio-office sudo[11537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 03:17:16 volumio-office sudo[11537]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:16 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:16 volumio-office volumio[11410]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:17:16 volumio-office volumio[11410]: info: [1761981436964] CoreMusicLibrary::Adding element Media Servers Nov 01 03:17:16 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:16 volumio-office sudo[11539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 03:17:16 volumio-office sudo[11539]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:16 volumio-office volumio[11410]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:17:16 volumio-office volumio[11410]: info: [1761981436988] CoreMusicLibrary::Adding element Last_100 Nov 01 03:17:16 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:16 volumio-office sudo[11537]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:16 volumio-office volumio[11410]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:17:16 volumio-office volumio[11410]: info: [1761981436992] CoreMusicLibrary::Adding element Webradio Nov 01 03:17:16 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:16 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:17:16 volumio-office systemd[1]: mpd.service: Succeeded. Nov 01 03:17:16 volumio-office systemd[1]: Stopped Music Player Daemon. Nov 01 03:17:17 volumio-office volumio[11410]: info: Initializing BBC Radios Nov 01 03:17:17 volumio-office volumio[11410]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Nov 01 03:17:17 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:17:17 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:17:17 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:17:17 volumio-office systemd[1]: Starting Music Player Daemon... Nov 01 03:17:17 volumio-office systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 01 03:17:17 volumio-office systemd[1]: mpd.service: Succeeded. Nov 01 03:17:17 volumio-office systemd[1]: Stopped Music Player Daemon. Nov 01 03:17:17 volumio-office volumio[11410]: info: [squeezelite_mc] Starting proxy server... Nov 01 03:17:17 volumio-office systemd[1]: Starting Music Player Daemon... Nov 01 03:17:17 volumio-office volumio[11410]: info: Volumio Calling Home Nov 01 03:17:17 volumio-office volumio[11410]: info: [squeezelite_mc] Proxy server started on port 35863 Nov 01 03:17:17 volumio-office sudo[11553]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 03:17:17 volumio-office sudo[11553]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:17 volumio-office sudo[11553]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:17 volumio-office volumio[11410]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 03:17:17 volumio-office volumio[11410]: info: CoreStateMachine::pushState Nov 01 03:17:17 volumio-office volumio[11410]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:17 volumio-office volumio[11410]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:17 volumio-office volumio[11410]: info: [squeezelite_mc] Server discovery started Nov 01 03:17:17 volumio-office volumio[11410]: info: [squeezelite_mc] Player finder started Nov 01 03:17:17 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:17 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:17 volumio-office volumio[11410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:17 volumio-office volumio[11410]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 01 03:17:17 volumio-office volumio[11410]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 03:17:17 volumio-office volumio[11410]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 01 03:17:17 volumio-office volumio[11410]: at doSend (dgram.js:692:16) Nov 01 03:17:17 volumio-office volumio[11410]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 01 03:17:17 volumio-office volumio[11410]: at afterDns (dgram.js:638:5) Nov 01 03:17:17 volumio-office volumio[11410]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 01 03:17:17 volumio-office volumio[11410]: errno: -101, Nov 01 03:17:17 volumio-office volumio[11410]: code: 'ENETUNREACH', Nov 01 03:17:17 volumio-office volumio[11410]: syscall: 'send', Nov 01 03:17:17 volumio-office volumio[11410]: address: '255.255.255.255', Nov 01 03:17:17 volumio-office volumio[11410]: port: 3483 Nov 01 03:17:17 volumio-office volumio[11410]: } Nov 01 03:17:17 volumio-office volumio[11410]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 03:17:17 volumio-office sudo[11570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-01 03:16 Nov 01 03:17:17 volumio-office sudo[11570]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:17 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Nov 01 03:17:18 volumio-office sudo[11570]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:18 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Nov 01 03:17:18 volumio-office systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 01 03:17:18 volumio-office sudo[11533]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:18 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Nov 01 03:17:18 volumio-office sudo[11539]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:18 volumio-office systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 01 03:17:18 volumio-office systemd[1]: Started dynamicswap service. Nov 01 03:17:18 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Nov 01 03:17:18 volumio-office systemd[1]: dynamicswap.service: Succeeded. Nov 01 03:17:18 volumio-office systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 01 03:17:18 volumio-office systemd[1]: volumio.service: Scheduled restart job, restart counter is at 30. Nov 01 03:17:18 volumio-office systemd[1]: Stopped Volumio Backend Module. Nov 01 03:17:18 volumio-office systemd[1]: Started Volumio Backend Module. Nov 01 03:17:18 volumio-office systemd[1]: Started dynamicswap service. Nov 01 03:17:18 volumio-office systemd[1]: dynamicswap.service: Succeeded. Nov 01 03:17:18 volumio-office mpd[11558]: Nov 01 03:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 01 03:17:18 volumio-office systemd[1]: Started Music Player Daemon. Nov 01 03:17:19 volumio-office volumio[11597]: info: ------------------------------------------- Nov 01 03:17:19 volumio-office volumio[11597]: info: ----- Volumio3 ---- Nov 01 03:17:19 volumio-office volumio[11597]: info: ------------------------------------------- Nov 01 03:17:19 volumio-office volumio[11597]: info: ----- System startup ---- Nov 01 03:17:19 volumio-office volumio[11597]: info: ------------------------------------------- Nov 01 03:17:20 volumio-office volumio[11597]: info: MYVOLUMIO Environment detected Nov 01 03:17:20 volumio-office volumio[11597]: info: Plugin folders cleanup Nov 01 03:17:20 volumio-office volumio[11597]: info: Scanning into folder /volumio/app/plugins/ Nov 01 03:17:20 volumio-office volumio[11597]: info: Scanning category audio_interface Nov 01 03:17:20 volumio-office volumio[11597]: info: Scanning category miscellanea Nov 01 03:17:20 volumio-office volumio[11597]: info: Scanning category music_service Nov 01 03:17:20 volumio-office volumio[11597]: info: Scanning category plugins.json Nov 01 03:17:20 volumio-office volumio[11597]: info: Scanning category system_controller Nov 01 03:17:20 volumio-office volumio[11597]: info: Scanning category user_interface Nov 01 03:17:20 volumio-office volumio[11597]: info: Scanning into folder /data/plugins/ Nov 01 03:17:20 volumio-office volumio[11597]: info: Scanning category music_service Nov 01 03:17:20 volumio-office volumio[11597]: info: Scanning category user_interface Nov 01 03:17:20 volumio-office volumio[11597]: info: Plugin folders cleanup completed Nov 01 03:17:20 volumio-office volumio[11597]: info: ------------------------------------------- Nov 01 03:17:20 volumio-office volumio[11597]: info: ----- Core plugins startup ---- Nov 01 03:17:20 volumio-office volumio[11597]: info: ------------------------------------------- Nov 01 03:17:20 volumio-office volumio[11597]: info: Loading plugins from folder /volumio/app/plugins/ Nov 01 03:17:20 volumio-office volumio[11597]: info: Adding plugin upnp to MyMusic Plugins Nov 01 03:17:20 volumio-office volumio[11597]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 01 03:17:20 volumio-office volumio[11597]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 01 03:17:20 volumio-office volumio[11597]: info: Loading plugins from folder /data/plugins/ Nov 01 03:17:20 volumio-office volumio[11597]: info: Loading plugin "system"... Nov 01 03:17:20 volumio-office volumio[11597]: info: Loading plugin "appearance"... Nov 01 03:17:20 volumio-office wpa_supplicant[825]: wlan0: Trying to associate with SSID 'Home' Nov 01 03:17:20 volumio-office wpa_supplicant[825]: wlan0: Associated with 3a:de:4b:76:33:8c Nov 01 03:17:20 volumio-office wpa_supplicant[825]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3a:de:4b:76:33:8c completed [id=0 id_str=] Nov 01 03:17:20 volumio-office dhcpcd[839]: wlan0: carrier acquired Nov 01 03:17:20 volumio-office wpa_supplicant[825]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 01 03:17:20 volumio-office wpa_supplicant[825]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Nov 01 03:17:20 volumio-office dhcpcd[839]: wlan0: IAID eb:53:22:24 Nov 01 03:17:21 volumio-office dhcpcd[839]: wlan0: soliciting an IPv6 router Nov 01 03:17:21 volumio-office dhcpcd[839]: wlan0: rebinding lease of 192.168.3.66 Nov 01 03:17:21 volumio-office wpa_supplicant[825]: RRM: Ignoring radio measurement request: Not RRM network Nov 01 03:17:21 volumio-office volumio[11597]: info: Loading plugin "network"... Nov 01 03:17:21 volumio-office volumio[11597]: info: Refreshing Cached IP Addresses Nov 01 03:17:21 volumio-office sudo[11630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 01 03:17:21 volumio-office sudo[11630]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:21 volumio-office sudo[11632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 01 03:17:21 volumio-office sudo[11632]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:21 volumio-office sudo[11632]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:21 volumio-office sudo[11630]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:21 volumio-office volumio[11597]: info: Loading plugin "services"... Nov 01 03:17:21 volumio-office volumio[11597]: info: Loading plugin "alsa_controller"... Nov 01 03:17:21 volumio-office sudo[11643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 01 03:17:21 volumio-office sudo[11643]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:21 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:17:21 volumio-office volumio[11597]: info: Loading plugin "wizard"... Nov 01 03:17:21 volumio-office volumio[11597]: info: Loading plugin "networkfs"... Nov 01 03:17:21 volumio-office volumio[11597]: info: Starting Udev Watcher for removable devices Nov 01 03:17:21 volumio-office volumio[11597]: info: Ignoring mount for partition: boot Nov 01 03:17:21 volumio-office volumio[11597]: info: Ignoring mount for partition: volumio Nov 01 03:17:21 volumio-office volumio[11597]: info: Ignoring mount for partition: volumio_data Nov 01 03:17:21 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:17:21 volumio-office volumio[11597]: info: Loading plugin "volumio_command_line_client"... Nov 01 03:17:21 volumio-office volumio[11597]: info: Loading plugin "upnp"... Nov 01 03:17:21 volumio-office volumio[11597]: info: [1761981441935] Starting Upmpd Daemon Nov 01 03:17:21 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:17:21 volumio-office volumio[11597]: info: Loading plugin "my_music"... Nov 01 03:17:21 volumio-office volumio[11597]: info: Loading plugin "mpd"... Nov 01 03:17:22 volumio-office volumio[11597]: info: Loading plugin "upnp_browser"... Nov 01 03:17:22 volumio-office volumio[11597]: info: Loading plugin "alarm-clock"... Nov 01 03:17:22 volumio-office volumio[11597]: info: Plugin airplay_emulation is not enabled Nov 01 03:17:22 volumio-office volumio[11597]: info: Loading plugin "last_100"... Nov 01 03:17:22 volumio-office volumio[11597]: info: Loading plugin "webradio"... Nov 01 03:17:22 volumio-office volumio[11597]: info: Loading plugin "i2s_dacs"... Nov 01 03:17:22 volumio-office volumio[11597]: info: I2S DAC not set, start Auto-detection Nov 01 03:17:22 volumio-office volumio[11597]: info: Loading plugin "volumiodiscovery"... Nov 01 03:17:22 volumio-office volumio[11597]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 03:17:22 volumio-office volumio[11597]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:17:22 volumio-office volumio[11597]: *** WARNING *** For more information see Nov 01 03:17:22 volumio-office node[11597]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 03:17:22 volumio-office volumio[11597]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 03:17:22 volumio-office volumio[11597]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:17:22 volumio-office volumio[11597]: *** WARNING *** For more information see Nov 01 03:17:22 volumio-office node[11597]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:17:22 volumio-office node[11597]: *** WARNING *** For more information see Nov 01 03:17:22 volumio-office node[11597]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 03:17:22 volumio-office node[11597]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:17:22 volumio-office node[11597]: *** WARNING *** For more information see Nov 01 03:17:22 volumio-office volumio[11597]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 01 03:17:22 volumio-office volumio[11597]: info: Discovery: Started advertising with name: Volumio-Office Nov 01 03:17:22 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:17:22 volumio-office volumio[11597]: info: Loading plugin "squeezelite_mc"... Nov 01 03:17:23 volumio-office volumio[11597]: info: Loading plugin "outputs"... Nov 01 03:17:23 volumio-office volumio[11597]: info: Loading plugin "albumart"... Nov 01 03:17:23 volumio-office volumio[11597]: info: Plugin example_plugin is not enabled Nov 01 03:17:23 volumio-office volumio[11597]: info: Loading plugin "inputs"... Nov 01 03:17:23 volumio-office volumio[11597]: info: Loading plugin "updater_comm"... Nov 01 03:17:23 volumio-office volumio[11597]: info: Plugin mpdemulation is not enabled Nov 01 03:17:23 volumio-office volumio[11597]: info: Loading plugin "rest_api"... Nov 01 03:17:23 volumio-office volumio[11597]: info: Loading plugin "websocket"... Nov 01 03:17:23 volumio-office volumio[11597]: info: Starting Socket.io Server version 2.3.0 Nov 01 03:17:23 volumio-office volumio[11597]: info: Loading plugin "Systeminfo"... Nov 01 03:17:24 volumio-office volumio[11597]: Forking 3 albumart workers Nov 01 03:17:24 volumio-office volumio[11597]: info: Loading i18n strings for locale en Nov 01 03:17:24 volumio-office volumio[11597]: Updating browse sources language Nov 01 03:17:24 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:24 volumio-office dhcpcd[839]: wlan0: probing address 192.168.3.66/22 Nov 01 03:17:24 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:24 volumio-office volumio[11597]: info: CoreCommandRouter::initPlayerControls Nov 01 03:17:24 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:24 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:24 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:24 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:24 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:24 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:24 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:24 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:24 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:24 volumio-office volumio[11597]: Express server listening on port 3000 Nov 01 03:17:24 volumio-office volumio[11597]: [Metrics] WebUI: 5s 462.46ms Nov 01 03:17:24 volumio-office volumio[11597]: info: CoreStateMachine::resetVolumioState Nov 01 03:17:24 volumio-office volumio[11597]: info: CoreStateMachine::getcurrentVolume Nov 01 03:17:24 volumio-office volumio[11597]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 03:17:24 volumio-office volumio[11597]: info: CoreStateMachine::pushState Nov 01 03:17:24 volumio-office volumio[11597]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:24 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 03:17:24 volumio-office volumio[11597]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:24 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:17:25 volumio-office volumio[11597]: info: Volumio Network Manager: Network status updated: 0 Nov 01 03:17:25 volumio-office volumio[11597]: info: Reloading queue from file Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreStateMachine::setRepeat null single undefined Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreStateMachine::pushState Nov 01 03:17:25 volumio-office volumio[11597]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:25 volumio-office volumio[11597]: Starting albumart workers Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreStateMachine::setRandom null Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreStateMachine::pushState Nov 01 03:17:25 volumio-office volumio[11597]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:25 volumio-office volumio[11597]: info: Setting Device type: Raspberry PI Nov 01 03:17:25 volumio-office volumio[11597]: info: Completed loading Core Plugins Nov 01 03:17:25 volumio-office volumio[11597]: info: Preparing to generate the ALSA configuration file Nov 01 03:17:25 volumio-office volumio[11597]: Starting albumart workers Nov 01 03:17:25 volumio-office volumio[11597]: info: Asound.conf file unchanged, so no further update is needed Nov 01 03:17:25 volumio-office volumio[11597]: Starting albumart workers Nov 01 03:17:25 volumio-office volumio[11597]: info: Output device has changed, restarting MPD Nov 01 03:17:25 volumio-office volumio[11597]: info: ___________ START PLUGINS ___________ Nov 01 03:17:25 volumio-office sudo[11695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 03:17:25 volumio-office sudo[11695]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:25 volumio-office volumio[11597]: info: ControllerMpd::onStart: Initializing MPD Nov 01 03:17:25 volumio-office volumio[11597]: info: Creating MPD Configuration file Nov 01 03:17:25 volumio-office sudo[11697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 03:17:25 volumio-office sudo[11695]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:25 volumio-office sudo[11697]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:17:25 volumio-office volumio[11597]: info: [1761981445279] CoreMusicLibrary::Adding element Media Servers Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:25 volumio-office systemd[1]: Stopping Music Player Daemon... Nov 01 03:17:25 volumio-office sudo[11703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 03:17:25 volumio-office sudo[11700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 03:17:25 volumio-office sudo[11700]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:25 volumio-office sudo[11700]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:25 volumio-office sudo[11703]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:25 volumio-office systemd[1]: mpd.service: Succeeded. Nov 01 03:17:25 volumio-office systemd[1]: Stopped Music Player Daemon. Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:17:25 volumio-office volumio[11597]: info: [1761981445350] CoreMusicLibrary::Adding element Last_100 Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:17:25 volumio-office volumio[11597]: info: [1761981445367] CoreMusicLibrary::Adding element Webradio Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:17:25 volumio-office volumio[11597]: info: Initializing BBC Radios Nov 01 03:17:25 volumio-office systemd[1]: Starting Music Player Daemon... Nov 01 03:17:25 volumio-office volumio[11597]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Nov 01 03:17:25 volumio-office systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 01 03:17:25 volumio-office systemd[1]: mpd.service: Succeeded. Nov 01 03:17:25 volumio-office systemd[1]: Stopped Music Player Daemon. Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:17:25 volumio-office systemd[1]: Starting Music Player Daemon... Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:17:25 volumio-office volumio[11597]: info: [squeezelite_mc] Starting proxy server... Nov 01 03:17:25 volumio-office volumio[11597]: info: Volumio Calling Home Nov 01 03:17:25 volumio-office sudo[11709]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 03:17:25 volumio-office sudo[11709]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:25 volumio-office sudo[11709]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:25 volumio-office volumio[11597]: info: [squeezelite_mc] Proxy server started on port 36633 Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreStateMachine::pushState Nov 01 03:17:25 volumio-office volumio[11597]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:25 volumio-office sudo[11643]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:25 volumio-office volumio-remote-updater[507]: [2025-11-01 03:17:25] [connect] Successful connection Nov 01 03:17:25 volumio-office volumio[11597]: info: [squeezelite_mc] Server discovery started Nov 01 03:17:25 volumio-office volumio[11597]: info: [squeezelite_mc] Player finder started Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:25 volumio-office volumio[11597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:25 volumio-office volumio[11597]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 01 03:17:26 volumio-office volumio[11597]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 03:17:26 volumio-office volumio[11597]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 01 03:17:26 volumio-office volumio[11597]: at doSend (dgram.js:692:16) Nov 01 03:17:26 volumio-office volumio[11597]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 01 03:17:26 volumio-office volumio[11597]: at afterDns (dgram.js:638:5) Nov 01 03:17:26 volumio-office volumio[11597]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 01 03:17:26 volumio-office volumio[11597]: errno: -101, Nov 01 03:17:26 volumio-office volumio[11597]: code: 'ENETUNREACH', Nov 01 03:17:26 volumio-office volumio[11597]: syscall: 'send', Nov 01 03:17:26 volumio-office volumio[11597]: address: '255.255.255.255', Nov 01 03:17:26 volumio-office volumio[11597]: port: 3483 Nov 01 03:17:26 volumio-office volumio[11597]: } Nov 01 03:17:26 volumio-office volumio[11597]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 03:17:26 volumio-office sudo[11758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-01 03:16 Nov 01 03:17:26 volumio-office sudo[11758]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:26 volumio-office sudo[11758]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:26 volumio-office volumio-remote-updater[507]: [2025-11-01 03:17:26] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Nov 01 03:17:26 volumio-office volumio-remote-updater[507]: [2025-11-01 03:17:26] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Nov 01 03:17:26 volumio-office volumio-remote-updater[507]: [2025-11-01 03:17:26] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 01 03:17:26 volumio-office systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 01 03:17:26 volumio-office sudo[11697]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:26 volumio-office sudo[11703]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:26 volumio-office systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 01 03:17:26 volumio-office systemd[1]: Started dynamicswap service. Nov 01 03:17:27 volumio-office systemd[1]: dynamicswap.service: Succeeded. Nov 01 03:17:27 volumio-office systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 01 03:17:27 volumio-office systemd[1]: volumio.service: Scheduled restart job, restart counter is at 31. Nov 01 03:17:27 volumio-office systemd[1]: Started dynamicswap service. Nov 01 03:17:27 volumio-office systemd[1]: Stopped Volumio Backend Module. Nov 01 03:17:27 volumio-office systemd[1]: Started Volumio Backend Module. Nov 01 03:17:27 volumio-office systemd[1]: dynamicswap.service: Succeeded. Nov 01 03:17:27 volumio-office mpd[11721]: Nov 01 03:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 01 03:17:27 volumio-office systemd[1]: Started Music Player Daemon. Nov 01 03:17:28 volumio-office volumio[11772]: info: ------------------------------------------- Nov 01 03:17:28 volumio-office volumio[11772]: info: ----- Volumio3 ---- Nov 01 03:17:28 volumio-office volumio[11772]: info: ------------------------------------------- Nov 01 03:17:28 volumio-office volumio[11772]: info: ----- System startup ---- Nov 01 03:17:28 volumio-office volumio[11772]: info: ------------------------------------------- Nov 01 03:17:29 volumio-office volumio[11772]: info: MYVOLUMIO Environment detected Nov 01 03:17:29 volumio-office volumio[11772]: info: Plugin folders cleanup Nov 01 03:17:29 volumio-office volumio[11772]: info: Scanning into folder /volumio/app/plugins/ Nov 01 03:17:29 volumio-office volumio[11772]: info: Scanning category audio_interface Nov 01 03:17:29 volumio-office volumio[11772]: info: Scanning category miscellanea Nov 01 03:17:29 volumio-office volumio[11772]: info: Scanning category music_service Nov 01 03:17:29 volumio-office volumio[11772]: info: Scanning category plugins.json Nov 01 03:17:29 volumio-office volumio[11772]: info: Scanning category system_controller Nov 01 03:17:29 volumio-office volumio[11772]: info: Scanning category user_interface Nov 01 03:17:29 volumio-office volumio[11772]: info: Scanning into folder /data/plugins/ Nov 01 03:17:29 volumio-office volumio[11772]: info: Scanning category music_service Nov 01 03:17:29 volumio-office volumio[11772]: info: Scanning category user_interface Nov 01 03:17:29 volumio-office volumio[11772]: info: Plugin folders cleanup completed Nov 01 03:17:29 volumio-office volumio[11772]: info: ------------------------------------------- Nov 01 03:17:29 volumio-office volumio[11772]: info: ----- Core plugins startup ---- Nov 01 03:17:29 volumio-office volumio[11772]: info: ------------------------------------------- Nov 01 03:17:29 volumio-office volumio[11772]: info: Loading plugins from folder /volumio/app/plugins/ Nov 01 03:17:29 volumio-office volumio[11772]: info: Adding plugin upnp to MyMusic Plugins Nov 01 03:17:29 volumio-office volumio[11772]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 01 03:17:29 volumio-office volumio[11772]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 01 03:17:29 volumio-office volumio[11772]: info: Loading plugins from folder /data/plugins/ Nov 01 03:17:29 volumio-office volumio[11772]: info: Loading plugin "system"... Nov 01 03:17:29 volumio-office volumio[11772]: info: Loading plugin "appearance"... Nov 01 03:17:29 volumio-office dhcpcd[839]: wlan0: leased 192.168.3.66 for 7200 seconds Nov 01 03:17:29 volumio-office avahi-daemon[515]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.66. Nov 01 03:17:29 volumio-office dhcpcd[839]: wlan0: adding route to 192.168.0.0/22 Nov 01 03:17:29 volumio-office dhcpcd[839]: wlan0: adding default route via 192.168.0.1 Nov 01 03:17:29 volumio-office avahi-daemon[515]: New relevant interface wlan0.IPv4 for mDNS. Nov 01 03:17:29 volumio-office avahi-daemon[515]: Registering new address record for 192.168.3.66 on wlan0.IPv4. Nov 01 03:17:30 volumio-office volumio[11772]: info: Loading plugin "network"... Nov 01 03:17:30 volumio-office volumio[11772]: info: Refreshing Cached IP Addresses Nov 01 03:17:30 volumio-office sudo[11822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 01 03:17:30 volumio-office sudo[11822]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:30 volumio-office sudo[11824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 01 03:17:30 volumio-office sudo[11824]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:30 volumio-office sudo[11822]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:30 volumio-office volumio[11772]: info: Loading plugin "services"... Nov 01 03:17:30 volumio-office sudo[11824]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:30 volumio-office volumio[11772]: info: Loading plugin "alsa_controller"... Nov 01 03:17:30 volumio-office sudo[11836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 01 03:17:30 volumio-office sudo[11836]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:30 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:17:30 volumio-office volumio[11772]: info: Loading plugin "wizard"... Nov 01 03:17:30 volumio-office volumio[11772]: info: Loading plugin "networkfs"... Nov 01 03:17:30 volumio-office volumio[11772]: info: Starting Udev Watcher for removable devices Nov 01 03:17:30 volumio-office volumio[11772]: info: Ignoring mount for partition: boot Nov 01 03:17:30 volumio-office volumio[11772]: info: Ignoring mount for partition: volumio Nov 01 03:17:30 volumio-office volumio[11772]: info: Ignoring mount for partition: volumio_data Nov 01 03:17:30 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:17:30 volumio-office volumio[11772]: info: Loading plugin "volumio_command_line_client"... Nov 01 03:17:30 volumio-office volumio[11772]: info: Loading plugin "upnp"... Nov 01 03:17:30 volumio-office volumio[11772]: info: [1761981450736] Starting Upmpd Daemon Nov 01 03:17:30 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:17:30 volumio-office volumio[11772]: info: Loading plugin "my_music"... Nov 01 03:17:30 volumio-office volumio[11772]: info: Loading plugin "mpd"... Nov 01 03:17:30 volumio-office ntpd[665]: Listen normally on 11 wlan0 192.168.3.66:123 Nov 01 03:17:30 volumio-office ntpd[665]: new interface(s) found: waking up resolver Nov 01 03:17:30 volumio-office wpa_supplicant[825]: wlan0: CTRL-EVENT-DISCONNECTED bssid=3a:de:4b:76:33:8c reason=0 locally_generated=1 Nov 01 03:17:30 volumio-office dhcpcd[839]: wlan0: carrier lost Nov 01 03:17:30 volumio-office wpa_supplicant[825]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Nov 01 03:17:30 volumio-office wpa_supplicant[825]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US Nov 01 03:17:31 volumio-office avahi-daemon[515]: Withdrawing address record for 192.168.3.66 on wlan0. Nov 01 03:17:31 volumio-office avahi-daemon[515]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.66. Nov 01 03:17:31 volumio-office avahi-daemon[515]: Interface wlan0.IPv4 no longer relevant for mDNS. Nov 01 03:17:31 volumio-office dhcpcd[839]: wlan0: deleting route to 192.168.0.0/22 Nov 01 03:17:31 volumio-office dhcpcd[839]: wlan0: deleting default route via 192.168.0.1 Nov 01 03:17:31 volumio-office volumio[11772]: info: Loading plugin "upnp_browser"... Nov 01 03:17:31 volumio-office volumio[11772]: info: Loading plugin "alarm-clock"... Nov 01 03:17:31 volumio-office volumio[11772]: info: Plugin airplay_emulation is not enabled Nov 01 03:17:31 volumio-office volumio[11772]: info: Loading plugin "last_100"... Nov 01 03:17:31 volumio-office volumio[11772]: info: Loading plugin "webradio"... Nov 01 03:17:31 volumio-office volumio[11772]: info: Loading plugin "i2s_dacs"... Nov 01 03:17:31 volumio-office volumio[11772]: info: I2S DAC not set, start Auto-detection Nov 01 03:17:31 volumio-office volumio[11772]: info: Loading plugin "volumiodiscovery"... Nov 01 03:17:31 volumio-office volumio[11772]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 03:17:31 volumio-office volumio[11772]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:17:31 volumio-office volumio[11772]: *** WARNING *** For more information see Nov 01 03:17:31 volumio-office node[11772]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 03:17:31 volumio-office volumio[11772]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 03:17:31 volumio-office volumio[11772]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:17:31 volumio-office volumio[11772]: *** WARNING *** For more information see Nov 01 03:17:31 volumio-office node[11772]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:17:31 volumio-office node[11772]: *** WARNING *** For more information see Nov 01 03:17:31 volumio-office node[11772]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 03:17:31 volumio-office node[11772]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:17:31 volumio-office node[11772]: *** WARNING *** For more information see Nov 01 03:17:31 volumio-office volumio[11772]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 01 03:17:31 volumio-office volumio[11772]: info: Discovery: Started advertising with name: Volumio-Office Nov 01 03:17:31 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:17:31 volumio-office volumio[11772]: info: Loading plugin "squeezelite_mc"... Nov 01 03:17:32 volumio-office nmbd[639]: [2025/11/01 03:17:32.185886, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 01 03:17:32 volumio-office nmbd[639]: Packet send failed to 192.168.3.255(137) ERRNO=Network is unreachable Nov 01 03:17:32 volumio-office nmbd[639]: [2025/11/01 03:17:32.188010, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Nov 01 03:17:32 volumio-office nmbd[639]: retransmit_or_expire_response_records: Failed to resend packet id 11518 to IP 192.168.3.255 on subnet 192.168.3.66 Nov 01 03:17:32 volumio-office nmbd[639]: [2025/11/01 03:17:32.189171, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 01 03:17:32 volumio-office nmbd[639]: Packet send failed to 192.168.3.255(137) ERRNO=Network is unreachable Nov 01 03:17:32 volumio-office nmbd[639]: [2025/11/01 03:17:32.189400, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Nov 01 03:17:32 volumio-office nmbd[639]: retransmit_or_expire_response_records: Failed to resend packet id 11519 to IP 192.168.3.255 on subnet 192.168.3.66 Nov 01 03:17:32 volumio-office nmbd[639]: [2025/11/01 03:17:32.189508, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 01 03:17:32 volumio-office nmbd[639]: Packet send failed to 192.168.3.255(137) ERRNO=Network is unreachable Nov 01 03:17:32 volumio-office nmbd[639]: [2025/11/01 03:17:32.189581, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Nov 01 03:17:32 volumio-office nmbd[639]: retransmit_or_expire_response_records: Failed to resend packet id 11520 to IP 192.168.3.255 on subnet 192.168.3.66 Nov 01 03:17:32 volumio-office nmbd[639]: [2025/11/01 03:17:32.189656, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 01 03:17:32 volumio-office nmbd[639]: Packet send failed to 192.168.3.255(137) ERRNO=Network is unreachable Nov 01 03:17:32 volumio-office nmbd[639]: [2025/11/01 03:17:32.189723, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Nov 01 03:17:32 volumio-office nmbd[639]: retransmit_or_expire_response_records: Failed to resend packet id 11521 to IP 192.168.3.255 on subnet 192.168.3.66 Nov 01 03:17:32 volumio-office nmbd[639]: [2025/11/01 03:17:32.189796, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 01 03:17:32 volumio-office nmbd[639]: Packet send failed to 192.168.3.255(137) ERRNO=Network is unreachable Nov 01 03:17:32 volumio-office nmbd[639]: [2025/11/01 03:17:32.189868, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Nov 01 03:17:32 volumio-office nmbd[639]: retransmit_or_expire_response_records: Failed to resend packet id 11522 to IP 192.168.3.255 on subnet 192.168.3.66 Nov 01 03:17:32 volumio-office volumio[11772]: info: Loading plugin "outputs"... Nov 01 03:17:32 volumio-office volumio[11772]: info: Loading plugin "albumart"... Nov 01 03:17:32 volumio-office volumio[11772]: info: Plugin example_plugin is not enabled Nov 01 03:17:32 volumio-office volumio[11772]: info: Loading plugin "inputs"... Nov 01 03:17:32 volumio-office volumio[11772]: info: Loading plugin "updater_comm"... Nov 01 03:17:32 volumio-office volumio[11772]: info: Plugin mpdemulation is not enabled Nov 01 03:17:32 volumio-office volumio[11772]: info: Loading plugin "rest_api"... Nov 01 03:17:32 volumio-office volumio[11772]: info: Loading plugin "websocket"... Nov 01 03:17:32 volumio-office volumio[11772]: info: Starting Socket.io Server version 2.3.0 Nov 01 03:17:32 volumio-office volumio[11772]: info: Loading plugin "Systeminfo"... Nov 01 03:17:32 volumio-office ntpd[665]: Deleting interface #11 wlan0, 192.168.3.66#123, interface stats: received=0, sent=0, dropped=3, active_time=2 secs Nov 01 03:17:32 volumio-office ntpd[665]: 184.174.134.232 local addr 192.168.3.66 -> Nov 01 03:17:32 volumio-office ntpd[665]: 158.51.99.19 local addr 192.168.3.66 -> Nov 01 03:17:32 volumio-office ntpd[665]: 102.129.185.135 local addr 192.168.3.66 -> Nov 01 03:17:32 volumio-office ntpd[665]: 162.159.200.1 local addr 192.168.3.66 -> Nov 01 03:17:32 volumio-office ntpd[665]: 96.231.54.40 local addr 192.168.3.66 -> Nov 01 03:17:32 volumio-office ntpd[665]: 170.187.147.56 local addr 192.168.3.66 -> Nov 01 03:17:32 volumio-office volumio[11772]: Forking 3 albumart workers Nov 01 03:17:33 volumio-office nmbd[639]: [2025/11/01 03:17:33.191236, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 01 03:17:33 volumio-office nmbd[639]: Packet send failed to 192.168.3.255(137) ERRNO=Network is unreachable Nov 01 03:17:33 volumio-office nmbd[639]: [2025/11/01 03:17:33.191522, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Nov 01 03:17:33 volumio-office nmbd[639]: retransmit_or_expire_response_records: Failed to resend packet id 11518 to IP 192.168.3.255 on subnet 192.168.3.66 Nov 01 03:17:33 volumio-office nmbd[639]: [2025/11/01 03:17:33.191645, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 01 03:17:33 volumio-office nmbd[639]: Packet send failed to 192.168.3.255(137) ERRNO=Network is unreachable Nov 01 03:17:33 volumio-office nmbd[639]: [2025/11/01 03:17:33.191727, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Nov 01 03:17:33 volumio-office nmbd[639]: retransmit_or_expire_response_records: Failed to resend packet id 11519 to IP 192.168.3.255 on subnet 192.168.3.66 Nov 01 03:17:33 volumio-office nmbd[639]: [2025/11/01 03:17:33.191807, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 01 03:17:33 volumio-office nmbd[639]: Packet send failed to 192.168.3.255(137) ERRNO=Network is unreachable Nov 01 03:17:33 volumio-office nmbd[639]: [2025/11/01 03:17:33.191879, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Nov 01 03:17:33 volumio-office nmbd[639]: retransmit_or_expire_response_records: Failed to resend packet id 11520 to IP 192.168.3.255 on subnet 192.168.3.66 Nov 01 03:17:33 volumio-office nmbd[639]: [2025/11/01 03:17:33.191951, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 01 03:17:33 volumio-office nmbd[639]: Packet send failed to 192.168.3.255(137) ERRNO=Network is unreachable Nov 01 03:17:33 volumio-office nmbd[639]: [2025/11/01 03:17:33.192051, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Nov 01 03:17:33 volumio-office nmbd[639]: retransmit_or_expire_response_records: Failed to resend packet id 11521 to IP 192.168.3.255 on subnet 192.168.3.66 Nov 01 03:17:33 volumio-office nmbd[639]: [2025/11/01 03:17:33.192124, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 01 03:17:33 volumio-office nmbd[639]: Packet send failed to 192.168.3.255(137) ERRNO=Network is unreachable Nov 01 03:17:33 volumio-office nmbd[639]: [2025/11/01 03:17:33.192192, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Nov 01 03:17:33 volumio-office nmbd[639]: retransmit_or_expire_response_records: Failed to resend packet id 11522 to IP 192.168.3.255 on subnet 192.168.3.66 Nov 01 03:17:33 volumio-office volumio[11772]: info: Loading i18n strings for locale en Nov 01 03:17:33 volumio-office volumio[11772]: Updating browse sources language Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreCommandRouter::initPlayerControls Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:33 volumio-office volumio[11772]: Express server listening on port 3000 Nov 01 03:17:33 volumio-office volumio[11772]: [Metrics] WebUI: 5s 429.87ms Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreStateMachine::resetVolumioState Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreStateMachine::getcurrentVolume Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreStateMachine::pushState Nov 01 03:17:33 volumio-office volumio[11772]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:17:33 volumio-office volumio[11772]: info: Volumio Network Manager: Network status updated: 2 Nov 01 03:17:33 volumio-office volumio[11772]: info: Reloading queue from file Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreStateMachine::setRepeat null single undefined Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreStateMachine::pushState Nov 01 03:17:33 volumio-office volumio[11772]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreStateMachine::setRandom null Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreStateMachine::pushState Nov 01 03:17:33 volumio-office volumio[11772]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:33 volumio-office volumio[11772]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:33 volumio-office volumio[11772]: info: Setting Device type: Raspberry PI Nov 01 03:17:33 volumio-office volumio[11772]: info: Completed loading Core Plugins Nov 01 03:17:33 volumio-office volumio[11772]: info: Preparing to generate the ALSA configuration file Nov 01 03:17:33 volumio-office volumio[11772]: Starting albumart workers Nov 01 03:17:33 volumio-office volumio[11772]: info: Asound.conf file unchanged, so no further update is needed Nov 01 03:17:33 volumio-office volumio[11772]: info: Output device has changed, restarting MPD Nov 01 03:17:33 volumio-office volumio[11772]: Starting albumart workers Nov 01 03:17:33 volumio-office volumio[11772]: info: ___________ START PLUGINS ___________ Nov 01 03:17:33 volumio-office volumio[11772]: info: ControllerMpd::onStart: Initializing MPD Nov 01 03:17:33 volumio-office volumio[11772]: info: Creating MPD Configuration file Nov 01 03:17:33 volumio-office sudo[11918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 03:17:33 volumio-office sudo[11918]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:33 volumio-office sudo[11920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 03:17:33 volumio-office sudo[11918]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:34 volumio-office volumio[11772]: Starting albumart workers Nov 01 03:17:34 volumio-office sudo[11920]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:34 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:34 volumio-office volumio[11772]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:17:34 volumio-office volumio[11772]: info: [1761981454029] CoreMusicLibrary::Adding element Media Servers Nov 01 03:17:34 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:34 volumio-office sudo[11923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 03:17:34 volumio-office sudo[11923]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:34 volumio-office systemd[1]: Stopping Music Player Daemon... Nov 01 03:17:34 volumio-office sudo[11923]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:34 volumio-office volumio[11772]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:17:34 volumio-office volumio[11772]: info: [1761981454073] CoreMusicLibrary::Adding element Last_100 Nov 01 03:17:34 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:34 volumio-office sudo[11926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 03:17:34 volumio-office sudo[11926]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:34 volumio-office volumio[11772]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:17:34 volumio-office volumio[11772]: info: [1761981454078] CoreMusicLibrary::Adding element Webradio Nov 01 03:17:34 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:34 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:17:34 volumio-office systemd[1]: mpd.service: Succeeded. Nov 01 03:17:34 volumio-office systemd[1]: Stopped Music Player Daemon. Nov 01 03:17:34 volumio-office volumio[11772]: info: Initializing BBC Radios Nov 01 03:17:34 volumio-office volumio[11772]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Nov 01 03:17:34 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:17:34 volumio-office systemd[1]: Starting Music Player Daemon... Nov 01 03:17:34 volumio-office nmbd[639]: [2025/11/01 03:17:34.195647, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 01 03:17:34 volumio-office nmbd[639]: Packet send failed to 192.168.3.255(137) ERRNO=Network is unreachable Nov 01 03:17:34 volumio-office nmbd[639]: [2025/11/01 03:17:34.197268, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Nov 01 03:17:34 volumio-office nmbd[639]: retransmit_or_expire_response_records: Failed to resend packet id 11518 to IP 192.168.3.255 on subnet 192.168.3.66 Nov 01 03:17:34 volumio-office nmbd[639]: [2025/11/01 03:17:34.197991, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 01 03:17:34 volumio-office nmbd[639]: Packet send failed to 192.168.3.255(137) ERRNO=Network is unreachable Nov 01 03:17:34 volumio-office nmbd[639]: [2025/11/01 03:17:34.198666, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Nov 01 03:17:34 volumio-office nmbd[639]: retransmit_or_expire_response_records: Failed to resend packet id 11519 to IP 192.168.3.255 on subnet 192.168.3.66 Nov 01 03:17:34 volumio-office nmbd[639]: [2025/11/01 03:17:34.199257, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 01 03:17:34 volumio-office nmbd[639]: Packet send failed to 192.168.3.255(137) ERRNO=Network is unreachable Nov 01 03:17:34 volumio-office nmbd[639]: [2025/11/01 03:17:34.199926, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Nov 01 03:17:34 volumio-office nmbd[639]: retransmit_or_expire_response_records: Failed to resend packet id 11520 to IP 192.168.3.255 on subnet 192.168.3.66 Nov 01 03:17:34 volumio-office nmbd[639]: [2025/11/01 03:17:34.200612, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 01 03:17:34 volumio-office nmbd[639]: Packet send failed to 192.168.3.255(137) ERRNO=Network is unreachable Nov 01 03:17:34 volumio-office nmbd[639]: [2025/11/01 03:17:34.201258, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Nov 01 03:17:34 volumio-office nmbd[639]: retransmit_or_expire_response_records: Failed to resend packet id 11521 to IP 192.168.3.255 on subnet 192.168.3.66 Nov 01 03:17:34 volumio-office nmbd[639]: [2025/11/01 03:17:34.203526, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 01 03:17:34 volumio-office nmbd[639]: Packet send failed to 192.168.3.255(137) ERRNO=Network is unreachable Nov 01 03:17:34 volumio-office systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 01 03:17:34 volumio-office systemd[1]: mpd.service: Succeeded. Nov 01 03:17:34 volumio-office nmbd[639]: [2025/11/01 03:17:34.205006, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Nov 01 03:17:34 volumio-office nmbd[639]: retransmit_or_expire_response_records: Failed to resend packet id 11522 to IP 192.168.3.255 on subnet 192.168.3.66 Nov 01 03:17:34 volumio-office systemd[1]: Stopped Music Player Daemon. Nov 01 03:17:34 volumio-office systemd[1]: Starting Music Player Daemon... Nov 01 03:17:34 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:17:34 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:17:34 volumio-office sudo[11836]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:34 volumio-office sudo[11937]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 03:17:34 volumio-office sudo[11937]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:34 volumio-office volumio[11772]: info: [squeezelite_mc] Starting proxy server... Nov 01 03:17:34 volumio-office sudo[11937]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:34 volumio-office volumio[11772]: info: Volumio Calling Home Nov 01 03:17:34 volumio-office volumio[11772]: info: [squeezelite_mc] Proxy server started on port 36681 Nov 01 03:17:34 volumio-office volumio[11772]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 03:17:34 volumio-office volumio[11772]: info: CoreStateMachine::pushState Nov 01 03:17:34 volumio-office volumio[11772]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:34 volumio-office volumio[11772]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:34 volumio-office volumio[11772]: info: [squeezelite_mc] Server discovery started Nov 01 03:17:34 volumio-office volumio[11772]: info: [squeezelite_mc] Player finder started Nov 01 03:17:34 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:34 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:34 volumio-office volumio[11772]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:34 volumio-office volumio[11772]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 01 03:17:34 volumio-office volumio[11772]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 03:17:34 volumio-office volumio[11772]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 01 03:17:34 volumio-office volumio[11772]: at doSend (dgram.js:692:16) Nov 01 03:17:34 volumio-office volumio[11772]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 01 03:17:34 volumio-office volumio[11772]: at afterDns (dgram.js:638:5) Nov 01 03:17:34 volumio-office volumio[11772]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 01 03:17:34 volumio-office volumio[11772]: errno: -101, Nov 01 03:17:34 volumio-office volumio[11772]: code: 'ENETUNREACH', Nov 01 03:17:34 volumio-office volumio[11772]: syscall: 'send', Nov 01 03:17:34 volumio-office volumio[11772]: address: '255.255.255.255', Nov 01 03:17:34 volumio-office volumio[11772]: port: 3483 Nov 01 03:17:34 volumio-office volumio[11772]: } Nov 01 03:17:34 volumio-office volumio[11772]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 03:17:35 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Nov 01 03:17:35 volumio-office sudo[11981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-01 03:16 Nov 01 03:17:35 volumio-office sudo[11981]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:35 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Nov 01 03:17:35 volumio-office sudo[11981]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:35 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Nov 01 03:17:35 volumio-office systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 01 03:17:35 volumio-office sudo[11920]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:35 volumio-office sudo[11926]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:35 volumio-office systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 01 03:17:35 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Nov 01 03:17:35 volumio-office systemd[1]: Started dynamicswap service. Nov 01 03:17:35 volumio-office systemd[1]: dynamicswap.service: Succeeded. Nov 01 03:17:36 volumio-office mpd[11942]: Nov 01 03:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 01 03:17:36 volumio-office systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 01 03:17:36 volumio-office systemd[1]: volumio.service: Scheduled restart job, restart counter is at 32. Nov 01 03:17:36 volumio-office systemd[1]: Started Music Player Daemon. Nov 01 03:17:36 volumio-office systemd[1]: Stopped Volumio Backend Module. Nov 01 03:17:36 volumio-office systemd[1]: Started Volumio Backend Module. Nov 01 03:17:36 volumio-office systemd[1]: Started dynamicswap service. Nov 01 03:17:36 volumio-office systemd[1]: dynamicswap.service: Succeeded. Nov 01 03:17:36 volumio-office volumio-remote-updater[507]: [2025-11-01 03:17:36] [info] asio async_connect error: system:111 (Connection refused) Nov 01 03:17:36 volumio-office volumio-remote-updater[507]: [2025-11-01 03:17:36] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 01 03:17:36 volumio-office volumio-remote-updater[507]: [2025-11-01 03:17:36] [error] handle_connect error: Underlying Transport Error Nov 01 03:17:37 volumio-office volumio[11996]: info: ------------------------------------------- Nov 01 03:17:37 volumio-office volumio[11996]: info: ----- Volumio3 ---- Nov 01 03:17:37 volumio-office volumio[11996]: info: ------------------------------------------- Nov 01 03:17:37 volumio-office volumio[11996]: info: ----- System startup ---- Nov 01 03:17:37 volumio-office volumio[11996]: info: ------------------------------------------- Nov 01 03:17:38 volumio-office volumio[11996]: info: MYVOLUMIO Environment detected Nov 01 03:17:38 volumio-office volumio[11996]: info: Plugin folders cleanup Nov 01 03:17:38 volumio-office volumio[11996]: info: Scanning into folder /volumio/app/plugins/ Nov 01 03:17:38 volumio-office volumio[11996]: info: Scanning category audio_interface Nov 01 03:17:38 volumio-office volumio[11996]: info: Scanning category miscellanea Nov 01 03:17:38 volumio-office volumio[11996]: info: Scanning category music_service Nov 01 03:17:38 volumio-office volumio[11996]: info: Scanning category plugins.json Nov 01 03:17:38 volumio-office volumio[11996]: info: Scanning category system_controller Nov 01 03:17:38 volumio-office volumio[11996]: info: Scanning category user_interface Nov 01 03:17:38 volumio-office volumio[11996]: info: Scanning into folder /data/plugins/ Nov 01 03:17:38 volumio-office volumio[11996]: info: Scanning category music_service Nov 01 03:17:38 volumio-office volumio[11996]: info: Scanning category user_interface Nov 01 03:17:38 volumio-office volumio[11996]: info: Plugin folders cleanup completed Nov 01 03:17:38 volumio-office volumio[11996]: info: ------------------------------------------- Nov 01 03:17:38 volumio-office volumio[11996]: info: ----- Core plugins startup ---- Nov 01 03:17:38 volumio-office volumio[11996]: info: ------------------------------------------- Nov 01 03:17:38 volumio-office volumio[11996]: info: Loading plugins from folder /volumio/app/plugins/ Nov 01 03:17:38 volumio-office volumio[11996]: info: Adding plugin upnp to MyMusic Plugins Nov 01 03:17:38 volumio-office volumio[11996]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 01 03:17:38 volumio-office volumio[11996]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 01 03:17:38 volumio-office volumio[11996]: info: Loading plugins from folder /data/plugins/ Nov 01 03:17:38 volumio-office volumio[11996]: info: Loading plugin "system"... Nov 01 03:17:38 volumio-office volumio[11996]: info: Loading plugin "appearance"... Nov 01 03:17:39 volumio-office volumio[11996]: info: Loading plugin "network"... Nov 01 03:17:39 volumio-office volumio[11996]: info: Refreshing Cached IP Addresses Nov 01 03:17:39 volumio-office sudo[12064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 01 03:17:39 volumio-office sudo[12064]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:39 volumio-office sudo[12066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 01 03:17:39 volumio-office sudo[12066]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:39 volumio-office sudo[12066]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:39 volumio-office sudo[12064]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:39 volumio-office volumio[11996]: info: Loading plugin "services"... Nov 01 03:17:39 volumio-office volumio[11996]: info: Loading plugin "alsa_controller"... Nov 01 03:17:39 volumio-office sudo[12081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 01 03:17:39 volumio-office sudo[12081]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:39 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:17:39 volumio-office volumio[11996]: info: Loading plugin "wizard"... Nov 01 03:17:39 volumio-office volumio[11996]: info: Loading plugin "networkfs"... Nov 01 03:17:39 volumio-office volumio[11996]: info: Starting Udev Watcher for removable devices Nov 01 03:17:39 volumio-office volumio[11996]: info: Ignoring mount for partition: boot Nov 01 03:17:39 volumio-office volumio[11996]: info: Ignoring mount for partition: volumio Nov 01 03:17:39 volumio-office volumio[11996]: info: Ignoring mount for partition: volumio_data Nov 01 03:17:39 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:17:39 volumio-office volumio[11996]: info: Loading plugin "volumio_command_line_client"... Nov 01 03:17:39 volumio-office volumio[11996]: info: Loading plugin "upnp"... Nov 01 03:17:39 volumio-office volumio[11996]: info: [1761981459692] Starting Upmpd Daemon Nov 01 03:17:39 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:17:39 volumio-office volumio[11996]: info: Loading plugin "my_music"... Nov 01 03:17:39 volumio-office volumio[11996]: info: Loading plugin "mpd"... Nov 01 03:17:39 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Nov 01 03:17:39 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Nov 01 03:17:39 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Nov 01 03:17:40 volumio-office volumio[11996]: info: Loading plugin "upnp_browser"... Nov 01 03:17:40 volumio-office kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Nov 01 03:17:40 volumio-office volumio[11996]: info: Loading plugin "alarm-clock"... Nov 01 03:17:40 volumio-office volumio[11996]: info: Plugin airplay_emulation is not enabled Nov 01 03:17:40 volumio-office volumio[11996]: info: Loading plugin "last_100"... Nov 01 03:17:40 volumio-office volumio[11996]: info: Loading plugin "webradio"... Nov 01 03:17:40 volumio-office volumio[11996]: info: Loading plugin "i2s_dacs"... Nov 01 03:17:40 volumio-office volumio[11996]: info: I2S DAC not set, start Auto-detection Nov 01 03:17:40 volumio-office volumio[11996]: info: Loading plugin "volumiodiscovery"... Nov 01 03:17:40 volumio-office volumio[11996]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 03:17:40 volumio-office volumio[11996]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:17:40 volumio-office volumio[11996]: *** WARNING *** For more information see Nov 01 03:17:40 volumio-office node[11996]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 03:17:40 volumio-office volumio[11996]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 03:17:40 volumio-office volumio[11996]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:17:40 volumio-office volumio[11996]: *** WARNING *** For more information see Nov 01 03:17:40 volumio-office node[11996]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:17:40 volumio-office node[11996]: *** WARNING *** For more information see Nov 01 03:17:40 volumio-office node[11996]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 03:17:40 volumio-office node[11996]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:17:40 volumio-office node[11996]: *** WARNING *** For more information see Nov 01 03:17:40 volumio-office volumio[11996]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 01 03:17:40 volumio-office volumio[11996]: info: Discovery: Started advertising with name: Volumio-Office Nov 01 03:17:40 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:17:40 volumio-office volumio[11996]: info: Loading plugin "squeezelite_mc"... Nov 01 03:17:41 volumio-office volumio[11996]: info: Loading plugin "outputs"... Nov 01 03:17:41 volumio-office volumio[11996]: info: Loading plugin "albumart"... Nov 01 03:17:41 volumio-office volumio[11996]: info: Plugin example_plugin is not enabled Nov 01 03:17:41 volumio-office volumio[11996]: info: Loading plugin "inputs"... Nov 01 03:17:41 volumio-office volumio[11996]: info: Loading plugin "updater_comm"... Nov 01 03:17:41 volumio-office volumio[11996]: info: Plugin mpdemulation is not enabled Nov 01 03:17:41 volumio-office volumio[11996]: info: Loading plugin "rest_api"... Nov 01 03:17:41 volumio-office volumio[11996]: info: Loading plugin "websocket"... Nov 01 03:17:41 volumio-office volumio[11996]: info: Starting Socket.io Server version 2.3.0 Nov 01 03:17:41 volumio-office volumio[11996]: info: Loading plugin "Systeminfo"... Nov 01 03:17:41 volumio-office volumio[11996]: Forking 3 albumart workers Nov 01 03:17:42 volumio-office volumio[11996]: info: Loading i18n strings for locale en Nov 01 03:17:42 volumio-office volumio[11996]: Updating browse sources language Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::initPlayerControls Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:42 volumio-office volumio[11996]: Express server listening on port 3000 Nov 01 03:17:42 volumio-office volumio[11996]: [Metrics] WebUI: 5s 320.23ms Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreStateMachine::resetVolumioState Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreStateMachine::getcurrentVolume Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreStateMachine::pushState Nov 01 03:17:42 volumio-office volumio[11996]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:17:42 volumio-office wpa_supplicant[825]: wlan0: Trying to associate with SSID 'Home' Nov 01 03:17:42 volumio-office volumio[11996]: info: Volumio Network Manager: Network status updated: 0 Nov 01 03:17:42 volumio-office volumio[11996]: info: Reloading queue from file Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreStateMachine::setRepeat null single undefined Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreStateMachine::pushState Nov 01 03:17:42 volumio-office volumio[11996]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreStateMachine::setRandom null Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreStateMachine::pushState Nov 01 03:17:42 volumio-office volumio[11996]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:42 volumio-office volumio[11996]: info: Setting Device type: Raspberry PI Nov 01 03:17:42 volumio-office volumio[11996]: info: Completed loading Core Plugins Nov 01 03:17:42 volumio-office volumio[11996]: info: Preparing to generate the ALSA configuration file Nov 01 03:17:42 volumio-office volumio[11996]: info: Asound.conf file unchanged, so no further update is needed Nov 01 03:17:42 volumio-office volumio[11996]: info: Output device has changed, restarting MPD Nov 01 03:17:42 volumio-office volumio[11996]: info: ___________ START PLUGINS ___________ Nov 01 03:17:42 volumio-office volumio[11996]: info: ControllerMpd::onStart: Initializing MPD Nov 01 03:17:42 volumio-office volumio[11996]: info: Creating MPD Configuration file Nov 01 03:17:42 volumio-office sudo[12129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 03:17:42 volumio-office sudo[12129]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:42 volumio-office sudo[12129]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:42 volumio-office sudo[12131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 03:17:42 volumio-office sudo[12131]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:42 volumio-office sudo[12081]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:17:42 volumio-office volumio[11996]: info: [1761981462895] CoreMusicLibrary::Adding element Media Servers Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:42 volumio-office sudo[12134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 03:17:42 volumio-office sudo[12134]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:42 volumio-office sudo[12134]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:42 volumio-office systemd[1]: Stopping Music Player Daemon... Nov 01 03:17:42 volumio-office sudo[12137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 03:17:42 volumio-office sudo[12137]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:17:42 volumio-office volumio[11996]: info: [1761981462953] CoreMusicLibrary::Adding element Last_100 Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:42 volumio-office systemd[1]: mpd.service: Succeeded. Nov 01 03:17:42 volumio-office systemd[1]: Stopped Music Player Daemon. Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:17:42 volumio-office volumio[11996]: info: [1761981462967] CoreMusicLibrary::Adding element Webradio Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:42 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:17:42 volumio-office volumio[11996]: info: Initializing BBC Radios Nov 01 03:17:43 volumio-office volumio[11996]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Nov 01 03:17:43 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:17:43 volumio-office volumio[11996]: Starting albumart workers Nov 01 03:17:43 volumio-office volumio[11996]: Starting albumart workers Nov 01 03:17:43 volumio-office systemd[1]: Starting Music Player Daemon... Nov 01 03:17:43 volumio-office wpa_supplicant[825]: wlan0: Associated with 36:de:4b:76:33:8b Nov 01 03:17:43 volumio-office wpa_supplicant[825]: wlan0: CTRL-EVENT-CONNECTED - Connection to 36:de:4b:76:33:8b completed [id=0 id_str=] Nov 01 03:17:43 volumio-office dhcpcd[839]: wlan0: carrier acquired Nov 01 03:17:43 volumio-office wpa_supplicant[825]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 01 03:17:43 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:17:43 volumio-office wpa_supplicant[825]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Nov 01 03:17:43 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:17:43 volumio-office dhcpcd[839]: wlan0: IAID eb:53:22:24 Nov 01 03:17:43 volumio-office dhcpcd[839]: wlan0: rebinding lease of 192.168.3.66 Nov 01 03:17:43 volumio-office volumio[11996]: info: [squeezelite_mc] Starting proxy server... Nov 01 03:17:43 volumio-office volumio[11996]: Starting albumart workers Nov 01 03:17:43 volumio-office volumio[11996]: info: Volumio Calling Home Nov 01 03:17:43 volumio-office sudo[12140]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 03:17:43 volumio-office sudo[12140]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:43 volumio-office sudo[12140]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:43 volumio-office volumio[11996]: info: [squeezelite_mc] Proxy server started on port 40285 Nov 01 03:17:43 volumio-office dhcpcd[839]: wlan0: soliciting an IPv6 router Nov 01 03:17:43 volumio-office volumio[11996]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 03:17:43 volumio-office volumio[11996]: info: CoreStateMachine::pushState Nov 01 03:17:43 volumio-office volumio[11996]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:43 volumio-office volumio[11996]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:43 volumio-office volumio[11996]: info: [squeezelite_mc] Server discovery started Nov 01 03:17:43 volumio-office volumio[11996]: info: [squeezelite_mc] Player finder started Nov 01 03:17:43 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:43 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:43 volumio-office volumio[11996]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:43 volumio-office volumio[11996]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 01 03:17:43 volumio-office volumio[11996]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 03:17:43 volumio-office volumio[11996]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 01 03:17:43 volumio-office volumio[11996]: at doSend (dgram.js:692:16) Nov 01 03:17:43 volumio-office volumio[11996]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 01 03:17:43 volumio-office volumio[11996]: at afterDns (dgram.js:638:5) Nov 01 03:17:43 volumio-office volumio[11996]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 01 03:17:43 volumio-office volumio[11996]: errno: -101, Nov 01 03:17:43 volumio-office volumio[11996]: code: 'ENETUNREACH', Nov 01 03:17:43 volumio-office volumio[11996]: syscall: 'send', Nov 01 03:17:43 volumio-office volumio[11996]: address: '255.255.255.255', Nov 01 03:17:43 volumio-office volumio[11996]: port: 3483 Nov 01 03:17:43 volumio-office volumio[11996]: } Nov 01 03:17:43 volumio-office volumio[11996]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 03:17:44 volumio-office sudo[12192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-01 03:16 Nov 01 03:17:44 volumio-office sudo[12192]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:44 volumio-office sudo[12192]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:44 volumio-office systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 01 03:17:44 volumio-office sudo[12131]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:44 volumio-office sudo[12137]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:44 volumio-office systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 01 03:17:44 volumio-office ntpd[665]: Soliciting pool server 108.61.73.244 Nov 01 03:17:44 volumio-office systemd[1]: Started dynamicswap service. Nov 01 03:17:44 volumio-office systemd[1]: dynamicswap.service: Succeeded. Nov 01 03:17:44 volumio-office mpd[12155]: Nov 01 03:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 01 03:17:44 volumio-office systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 01 03:17:44 volumio-office systemd[1]: volumio.service: Scheduled restart job, restart counter is at 33. Nov 01 03:17:44 volumio-office systemd[1]: Started Music Player Daemon. Nov 01 03:17:44 volumio-office systemd[1]: Stopped Volumio Backend Module. Nov 01 03:17:44 volumio-office systemd[1]: Started Volumio Backend Module. Nov 01 03:17:44 volumio-office systemd[1]: Started dynamicswap service. Nov 01 03:17:44 volumio-office systemd[1]: dynamicswap.service: Succeeded. Nov 01 03:17:46 volumio-office volumio[12207]: info: ------------------------------------------- Nov 01 03:17:46 volumio-office volumio[12207]: info: ----- Volumio3 ---- Nov 01 03:17:46 volumio-office volumio[12207]: info: ------------------------------------------- Nov 01 03:17:46 volumio-office volumio[12207]: info: ----- System startup ---- Nov 01 03:17:46 volumio-office volumio[12207]: info: ------------------------------------------- Nov 01 03:17:46 volumio-office volumio-remote-updater[507]: [2025-11-01 03:17:46] [connect] Successful connection Nov 01 03:17:47 volumio-office volumio[12207]: info: MYVOLUMIO Environment detected Nov 01 03:17:47 volumio-office volumio[12207]: info: Plugin folders cleanup Nov 01 03:17:47 volumio-office volumio[12207]: info: Scanning into folder /volumio/app/plugins/ Nov 01 03:17:47 volumio-office volumio[12207]: info: Scanning category audio_interface Nov 01 03:17:47 volumio-office volumio[12207]: info: Scanning category miscellanea Nov 01 03:17:47 volumio-office volumio[12207]: info: Scanning category music_service Nov 01 03:17:47 volumio-office volumio[12207]: info: Scanning category plugins.json Nov 01 03:17:47 volumio-office volumio[12207]: info: Scanning category system_controller Nov 01 03:17:47 volumio-office volumio[12207]: info: Scanning category user_interface Nov 01 03:17:47 volumio-office volumio[12207]: info: Scanning into folder /data/plugins/ Nov 01 03:17:47 volumio-office volumio[12207]: info: Scanning category music_service Nov 01 03:17:47 volumio-office volumio[12207]: info: Scanning category user_interface Nov 01 03:17:47 volumio-office volumio[12207]: info: Plugin folders cleanup completed Nov 01 03:17:47 volumio-office volumio[12207]: info: ------------------------------------------- Nov 01 03:17:47 volumio-office volumio[12207]: info: ----- Core plugins startup ---- Nov 01 03:17:47 volumio-office volumio[12207]: info: ------------------------------------------- Nov 01 03:17:47 volumio-office volumio[12207]: info: Loading plugins from folder /volumio/app/plugins/ Nov 01 03:17:47 volumio-office volumio[12207]: info: Adding plugin upnp to MyMusic Plugins Nov 01 03:17:47 volumio-office volumio[12207]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 01 03:17:47 volumio-office volumio[12207]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 01 03:17:47 volumio-office volumio[12207]: info: Loading plugins from folder /data/plugins/ Nov 01 03:17:47 volumio-office volumio[12207]: info: Loading plugin "system"... Nov 01 03:17:47 volumio-office volumio[12207]: info: Loading plugin "appearance"... Nov 01 03:17:47 volumio-office dhcpcd[839]: wlan0: probing address 192.168.3.66/22 Nov 01 03:17:48 volumio-office volumio[12207]: info: Loading plugin "network"... Nov 01 03:17:48 volumio-office volumio[12207]: info: Refreshing Cached IP Addresses Nov 01 03:17:48 volumio-office sudo[12234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 01 03:17:48 volumio-office sudo[12234]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:48 volumio-office sudo[12236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 01 03:17:48 volumio-office sudo[12236]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:48 volumio-office sudo[12236]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:48 volumio-office sudo[12234]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:48 volumio-office volumio[12207]: info: Loading plugin "services"... Nov 01 03:17:48 volumio-office volumio[12207]: info: Loading plugin "alsa_controller"... Nov 01 03:17:48 volumio-office sudo[12251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 01 03:17:48 volumio-office sudo[12251]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:48 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:17:48 volumio-office volumio[12207]: info: Loading plugin "wizard"... Nov 01 03:17:48 volumio-office volumio[12207]: info: Loading plugin "networkfs"... Nov 01 03:17:48 volumio-office volumio[12207]: info: Starting Udev Watcher for removable devices Nov 01 03:17:48 volumio-office volumio[12207]: info: Ignoring mount for partition: boot Nov 01 03:17:48 volumio-office volumio[12207]: info: Ignoring mount for partition: volumio Nov 01 03:17:48 volumio-office volumio[12207]: info: Ignoring mount for partition: volumio_data Nov 01 03:17:48 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:17:48 volumio-office volumio[12207]: info: Loading plugin "volumio_command_line_client"... Nov 01 03:17:48 volumio-office volumio[12207]: info: Loading plugin "upnp"... Nov 01 03:17:48 volumio-office volumio[12207]: info: [1761981468522] Starting Upmpd Daemon Nov 01 03:17:48 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:17:48 volumio-office volumio[12207]: info: Loading plugin "my_music"... Nov 01 03:17:48 volumio-office volumio[12207]: info: Loading plugin "mpd"... Nov 01 03:17:48 volumio-office volumio[12207]: info: Loading plugin "upnp_browser"... Nov 01 03:17:49 volumio-office volumio[12207]: info: Loading plugin "alarm-clock"... Nov 01 03:17:49 volumio-office volumio[12207]: info: Plugin airplay_emulation is not enabled Nov 01 03:17:49 volumio-office volumio[12207]: info: Loading plugin "last_100"... Nov 01 03:17:49 volumio-office volumio[12207]: info: Loading plugin "webradio"... Nov 01 03:17:49 volumio-office volumio[12207]: info: Loading plugin "i2s_dacs"... Nov 01 03:17:49 volumio-office volumio[12207]: info: I2S DAC not set, start Auto-detection Nov 01 03:17:49 volumio-office volumio[12207]: info: Loading plugin "volumiodiscovery"... Nov 01 03:17:49 volumio-office volumio[12207]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 03:17:49 volumio-office volumio[12207]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:17:49 volumio-office volumio[12207]: *** WARNING *** For more information see Nov 01 03:17:49 volumio-office node[12207]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 03:17:49 volumio-office volumio[12207]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 03:17:49 volumio-office volumio[12207]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:17:49 volumio-office volumio[12207]: *** WARNING *** For more information see Nov 01 03:17:49 volumio-office node[12207]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:17:49 volumio-office node[12207]: *** WARNING *** For more information see Nov 01 03:17:49 volumio-office node[12207]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 03:17:49 volumio-office node[12207]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 03:17:49 volumio-office node[12207]: *** WARNING *** For more information see Nov 01 03:17:49 volumio-office volumio[12207]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 01 03:17:49 volumio-office volumio[12207]: info: Discovery: Started advertising with name: Volumio-Office Nov 01 03:17:49 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 03:17:49 volumio-office volumio[12207]: info: Loading plugin "squeezelite_mc"... Nov 01 03:17:50 volumio-office volumio[12207]: info: Loading plugin "outputs"... Nov 01 03:17:50 volumio-office volumio[12207]: info: Loading plugin "albumart"... Nov 01 03:17:50 volumio-office volumio[12207]: info: Plugin example_plugin is not enabled Nov 01 03:17:50 volumio-office volumio[12207]: info: Loading plugin "inputs"... Nov 01 03:17:50 volumio-office volumio[12207]: info: Loading plugin "updater_comm"... Nov 01 03:17:50 volumio-office volumio[12207]: info: Plugin mpdemulation is not enabled Nov 01 03:17:50 volumio-office volumio[12207]: info: Loading plugin "rest_api"... Nov 01 03:17:50 volumio-office volumio[12207]: info: Loading plugin "websocket"... Nov 01 03:17:50 volumio-office volumio[12207]: info: Starting Socket.io Server version 2.3.0 Nov 01 03:17:50 volumio-office volumio[12207]: info: Loading plugin "Systeminfo"... Nov 01 03:17:50 volumio-office volumio[12207]: Forking 3 albumart workers Nov 01 03:17:51 volumio-office volumio[12207]: info: Loading i18n strings for locale en Nov 01 03:17:51 volumio-office volumio[12207]: Updating browse sources language Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::initPlayerControls Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:51 volumio-office volumio[12207]: Express server listening on port 3000 Nov 01 03:17:51 volumio-office volumio[12207]: [Metrics] WebUI: 5s 412.66ms Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreStateMachine::resetVolumioState Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreStateMachine::getcurrentVolume Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreStateMachine::pushState Nov 01 03:17:51 volumio-office volumio[12207]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:17:51 volumio-office volumio[12207]: info: Volumio Network Manager: Network status updated: 0 Nov 01 03:17:51 volumio-office volumio-remote-updater[507]: [2025-11-01 03:17:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1761981466 101 Nov 01 03:17:51 volumio-office volumio[12207]: 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 Nov 01 03:17:51 volumio-office volumio[12207]: Starting albumart workers Nov 01 03:17:51 volumio-office volumio[12207]: Starting albumart workers Nov 01 03:17:51 volumio-office volumio[12207]: info: Reloading queue from file Nov 01 03:17:51 volumio-office volumio[12207]: info: Setting Device type: Raspberry PI Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreStateMachine::setRepeat null single undefined Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreStateMachine::pushState Nov 01 03:17:51 volumio-office volumio[12207]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreStateMachine::setRandom null Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreStateMachine::pushState Nov 01 03:17:51 volumio-office volumio[12207]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:51 volumio-office volumio[12207]: Starting albumart workers Nov 01 03:17:51 volumio-office volumio[12207]: info: Completed loading Core Plugins Nov 01 03:17:51 volumio-office volumio[12207]: info: Preparing to generate the ALSA configuration file Nov 01 03:17:51 volumio-office volumio[12207]: info: Asound.conf file unchanged, so no further update is needed Nov 01 03:17:51 volumio-office volumio[12207]: info: Output device has changed, restarting MPD Nov 01 03:17:51 volumio-office volumio[12207]: info: ___________ START PLUGINS ___________ Nov 01 03:17:51 volumio-office volumio[12207]: info: ControllerMpd::onStart: Initializing MPD Nov 01 03:17:51 volumio-office volumio[12207]: info: Creating MPD Configuration file Nov 01 03:17:51 volumio-office sudo[12299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 03:17:51 volumio-office sudo[12299]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:51 volumio-office sudo[12301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 03:17:51 volumio-office sudo[12299]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:51 volumio-office sudo[12301]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:17:51 volumio-office volumio[12207]: info: [1761981471875] CoreMusicLibrary::Adding element Media Servers Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:51 volumio-office sudo[12304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 03:17:51 volumio-office sudo[12304]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:17:51 volumio-office volumio[12207]: info: [1761981471902] CoreMusicLibrary::Adding element Last_100 Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:51 volumio-office systemd[1]: Stopping Music Player Daemon... Nov 01 03:17:51 volumio-office sudo[12304]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 03:17:51 volumio-office volumio[12207]: info: [1761981471929] CoreMusicLibrary::Adding element Webradio Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 03:17:51 volumio-office sudo[12307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 03:17:51 volumio-office sudo[12307]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:51 volumio-office systemd[1]: mpd.service: Succeeded. Nov 01 03:17:51 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:17:51 volumio-office systemd[1]: Stopped Music Player Daemon. Nov 01 03:17:51 volumio-office volumio[12207]: info: Initializing BBC Radios Nov 01 03:17:51 volumio-office systemd[1]: Starting Music Player Daemon... Nov 01 03:17:52 volumio-office systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 01 03:17:52 volumio-office systemd[1]: mpd.service: Succeeded. Nov 01 03:17:52 volumio-office volumio[12207]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Nov 01 03:17:52 volumio-office systemd[1]: Stopped Music Player Daemon. Nov 01 03:17:52 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:17:52 volumio-office systemd[1]: Starting Music Player Daemon... Nov 01 03:17:52 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 03:17:52 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 03:17:52 volumio-office sudo[12313]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 03:17:52 volumio-office sudo[12313]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 03:17:52 volumio-office sudo[12313]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:52 volumio-office volumio[12207]: info: [squeezelite_mc] Starting proxy server... Nov 01 03:17:52 volumio-office volumio[12207]: info: Volumio Calling Home Nov 01 03:17:52 volumio-office volumio[12207]: info: [squeezelite_mc] Proxy server started on port 45907 Nov 01 03:17:52 volumio-office volumio[12207]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 03:17:52 volumio-office volumio[12207]: info: CoreStateMachine::pushState Nov 01 03:17:52 volumio-office volumio[12207]: info: CorePlayQueue::getTrack 0 Nov 01 03:17:52 volumio-office volumio[12207]: info: CoreCommandRouter::volumioPushState Nov 01 03:17:52 volumio-office sudo[12251]: pam_unix(sudo:session): session closed for user root Nov 01 03:17:52 volumio-office volumio[12207]: info: [squeezelite_mc] Server discovery started Nov 01 03:17:52 volumio-office volumio[12207]: info: [squeezelite_mc] Player finder started Nov 01 03:17:52 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:52 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 03:17:52 volumio-office volumio[12207]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 03:17:52 volumio-office volumio[12207]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 01 03:17:52 volumio-office volumio[12207]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 03:17:52 volumio-office volumio[12207]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 01 03:17:52 volumio-office volumio[12207]: at doSend (dgram.js:692:16) Nov 01 03:17:52 volumio-office volumio[12207]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Nov 01 03:17:52 volumio-office volumio[12207]: at afterDns (dgram.js:638:5) Nov 01 03:17:52 volumio-office volumio[12207]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Nov 01 03:17:52 volumio-office volumio[12207]: errno: -101, Nov 01 03:17:52 volumio-office volumio[12207]: code: 'ENETUNREACH', Nov 01 03:17:52 volumio-office volumio[12207]: syscall: 'send', Nov 01 03:17:52 volumio-office volumio[12207]: address: '255.255.255.255', Nov 01 03:17:52 volumio-office volumio[12207]: port: 3483 Nov 01 03:17:52 volumio-office volumio[12207]: } Nov 01 03:17:52 volumio-office volumio[12207]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 03:17:52 volumio-office dhcpcd[839]: wlan0: leased 192.168.3.66 for 7200 seconds Nov 01 03:17:52 volumio-office avahi-daemon[515]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.66. Nov 01 03:17:52 volumio-office avahi-daemon[515]: New relevant interface wlan0.IPv4 for mDNS. Nov 01 03:17:52 volumio-office dhcpcd[839]: wlan0: adding route to 192.168.0.0/22 Nov 01 03:17:52 volumio-office avahi-daemon[515]: Registering new address record for 192.168.3.66 on wlan0.IPv4. Nov 01 03:17:52 volumio-office dhcpcd[839]: wlan0: adding default route via 192.168.0.1 Nov 01 03:17:53 volumio-office sudo[12383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-01 03:16 Nov 01 03:17:53 volumio-office sudo[12383]: pam_unix(sudo:session): session opened for user root by (uid=0) 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"