-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Tue 2025-09-16 15:21:35 UTC. -- Sep 16 15:20:00 volumio volumio[3658]: info: Loading plugin "outputs"... Sep 16 15:20:00 volumio volumio[3658]: info: Loading plugin "albumart"... Sep 16 15:20:00 volumio volumio[3658]: info: Plugin example_plugin is not enabled Sep 16 15:20:00 volumio volumio[3658]: info: Loading plugin "inputs"... Sep 16 15:20:00 volumio volumio[3658]: info: Loading plugin "updater_comm"... Sep 16 15:20:00 volumio volumio[3658]: info: Plugin mpdemulation is not enabled Sep 16 15:20:00 volumio volumio[3658]: info: Loading plugin "rest_api"... Sep 16 15:20:00 volumio volumio[3658]: info: Loading plugin "websocket"... Sep 16 15:20:00 volumio volumio[3658]: info: Loading i18n strings for locale it Sep 16 15:20:00 volumio volumio[3658]: Updating browse sources language Sep 16 15:20:00 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:20:00 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:20:00 volumio volumio[3658]: info: CoreCommandRouter::initPlayerControls Sep 16 15:20:00 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:00 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:00 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:00 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:00 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:00 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:00 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:00 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:00 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:20:01 volumio volumio[3658]: Express server listening on port 3000 Sep 16 15:20:01 volumio volumio[3658]: [Metrics] WebUI: 7s 22.54ms Sep 16 15:20:01 volumio volumio[3658]: info: CoreStateMachine::resetVolumioState Sep 16 15:20:01 volumio volumio[3658]: info: CoreStateMachine::getcurrentVolume Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:01 volumio mpd[3701]: client: [0] opened from local Sep 16 15:20:01 volumio volumio[3658]: info: MPD Permissions set Sep 16 15:20:01 volumio volumio-remote-updater[484]: [2025-09-16 15:20:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1758035996 101 Sep 16 15:20:01 volumio volumio[3658]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Sep 16 15:20:01 volumio volumio[3658]: Forking 3 albumart workers Sep 16 15:20:01 volumio mpd[3701]: exception: No database Sep 16 15:20:01 volumio mpd[3701]: exception: No database Sep 16 15:20:01 volumio volumio[3658]: info: MPD running with PID3701 Sep 16 15:20:01 volumio volumio[3658]: ,establishing connection Sep 16 15:20:01 volumio mpd[3701]: client: [1] opened from local Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:01 volumio volumio[3658]: error: updateQueue error: null Sep 16 15:20:01 volumio volumio[3658]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Sep 16 15:20:01 volumio volumio[3658]: info: CoreStateMachine::setRepeat false single undefined Sep 16 15:20:01 volumio volumio[3658]: info: CoreStateMachine::pushState Sep 16 15:20:01 volumio volumio[3658]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:01 volumio volumio[3658]: info: CoreStateMachine::setRandom false Sep 16 15:20:01 volumio volumio[3658]: info: CoreStateMachine::pushState Sep 16 15:20:01 volumio volumio[3658]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:01 volumio volumio[3658]: info: Setting Device type: Raspberry PI Sep 16 15:20:01 volumio volumio[3658]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:20:01 volumio volumio[3658]: info: CoreStateMachine::pushState Sep 16 15:20:01 volumio volumio[3658]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:01 volumio volumio[3658]: info: CoreStateMachine::updateTrackBlock Sep 16 15:20:01 volumio volumio[3658]: info: CorePlayQueue::getTrackBlock Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:20:01 volumio volumio[3658]: error: updateQueue error: null Sep 16 15:20:01 volumio volumio[3658]: info: Completed loading Core Plugins Sep 16 15:20:01 volumio volumio[3658]: info: Preparing to generate the ALSA configuration file Sep 16 15:20:01 volumio volumio[3658]: info: Asound.conf file unchanged, so no further update is needed Sep 16 15:20:01 volumio volumio[3658]: info: Output device has changed, restarting MPD Sep 16 15:20:01 volumio volumio[3658]: info: Output device has changed, restarting Shairport Sync Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:01 volumio sudo[3766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 15:20:01 volumio sudo[3765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 15:20:01 volumio sudo[3765]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:01 volumio volumio[3658]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:20:01 volumio volumio[3658]: info: ___________ START PLUGINS ___________ Sep 16 15:20:01 volumio sudo[3766]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:20:01 volumio sudo[3765]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:20:01 volumio volumio[3658]: info: [1758036001848] CoreMusicLibrary::Adding element Media Servers Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:20:01 volumio volumio[3658]: Cannot find translation for sourceMedia Servers Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:01 volumio systemd[1]: Stopping Music Player Daemon... Sep 16 15:20:01 volumio systemd[1]: mpd.service: Succeeded. Sep 16 15:20:01 volumio systemd[1]: Stopped Music Player Daemon. Sep 16 15:20:01 volumio volumio[3658]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:01 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:01 volumio systemd[1]: Starting Music Player Daemon... Sep 16 15:20:02 volumio volumio[3658]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:20:02 volumio volumio[3658]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:20:02 volumio volumio[3658]: info: [1758036002200] CoreMusicLibrary::Adding element Last_100 Sep 16 15:20:02 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:20:02 volumio volumio[3658]: Cannot find translation for sourceMedia Servers Sep 16 15:20:02 volumio volumio[3658]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:20:02 volumio volumio[3658]: info: [1758036002205] CoreMusicLibrary::Adding element Webradio Sep 16 15:20:02 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:20:02 volumio volumio[3658]: Cannot find translation for sourceMedia Servers Sep 16 15:20:02 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 15:20:02 volumio volumio[3658]: info: [squeezelite_mc] Starting proxy server... Sep 16 15:20:02 volumio volumio[3658]: info: Volumio Calling Home Sep 16 15:20:02 volumio volumio[3658]: info: [squeezelite_mc] Proxy server started on port 33909 Sep 16 15:20:02 volumio volumio[3658]: Starting albumart workers Sep 16 15:20:02 volumio volumio[3658]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:20:02 volumio volumio[3658]: Unhandled rejection Error: No sockets available, cannot start. Sep 16 15:20:02 volumio volumio[3658]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Sep 16 15:20:02 volumio volumio[3658]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Sep 16 15:20:02 volumio volumio[3658]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Sep 16 15:20:02 volumio volumio[3658]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Sep 16 15:20:02 volumio volumio[3658]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Sep 16 15:20:02 volumio volumio[3658]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Sep 16 15:20:02 volumio volumio[3658]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Sep 16 15:20:02 volumio volumio[3658]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Sep 16 15:20:02 volumio volumio[3658]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Sep 16 15:20:02 volumio volumio[3658]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Sep 16 15:20:02 volumio volumio[3658]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Sep 16 15:20:02 volumio volumio[3658]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 16 15:20:02 volumio volumio[3658]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 16 15:20:02 volumio volumio[3658]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Sep 16 15:20:02 volumio volumio[3658]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Sep 16 15:20:02 volumio volumio[3658]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 16 15:20:03 volumio volumio[3658]: info: MPD Permissions set Sep 16 15:20:03 volumio volumio[3658]: Starting albumart workers Sep 16 15:20:03 volumio volumio[3658]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Sep 16 15:20:03 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:03 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:03 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:03 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:03 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:03 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:03 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:03 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:03 volumio volumio[3658]: Starting albumart workers Sep 16 15:20:03 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:03 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:03 volumio volumio[3658]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:20:03 volumio volumio[3658]: info: CoreStateMachine::pushState Sep 16 15:20:03 volumio volumio[3658]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:03 volumio volumio[3658]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:03 volumio volumio[3658]: info: [squeezelite_mc] Server discovery started Sep 16 15:20:03 volumio volumio[3658]: info: [squeezelite_mc] Player finder started Sep 16 15:20:03 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:03 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:03 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:03 volumio volumio[3658]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:20:03 volumio volumio[3658]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Sep 16 15:20:03 volumio volumio[3658]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 15:20:03 volumio volumio[3658]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 16 15:20:03 volumio volumio[3658]: at doSend (dgram.js:692:16) Sep 16 15:20:03 volumio volumio[3658]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Sep 16 15:20:03 volumio volumio[3658]: at afterDns (dgram.js:638:5) Sep 16 15:20:03 volumio volumio[3658]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 16 15:20:03 volumio volumio[3658]: errno: -101, Sep 16 15:20:03 volumio volumio[3658]: code: 'ENETUNREACH', Sep 16 15:20:03 volumio volumio[3658]: syscall: 'send', Sep 16 15:20:03 volumio volumio[3658]: address: '255.255.255.255', Sep 16 15:20:03 volumio volumio[3658]: port: 3483 Sep 16 15:20:03 volumio volumio[3658]: } Sep 16 15:20:03 volumio volumio[3658]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 15:20:04 volumio mpd[3780]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 15:20:04 volumio mpd[3780]: config: Found database setting without music_directory - disabling database Sep 16 15:20:04 volumio mpd[3780]: output: No 'audio_output' defined in config file Sep 16 15:20:04 volumio mpd[3780]: output: Attempt to detect audio output device Sep 16 15:20:04 volumio mpd[3780]: output: Attempting to detect a alsa audio device Sep 16 15:20:04 volumio mpd[3780]: alsa_output: Error opening default ALSA device: Device or resource busy Sep 16 15:20:04 volumio mpd[3780]: output: Attempting to detect a oss audio device Sep 16 15:20:04 volumio mpd[3780]: oss_output: Error opening OSS device "/dev/dsp": No such file or directory Sep 16 15:20:04 volumio mpd[3780]: oss_output: Error opening OSS device "/dev/sound/dsp": No such file or directory Sep 16 15:20:04 volumio mpd[3780]: output: Attempting to detect a pulse audio device Sep 16 15:20:04 volumio mpd[3780]: output: Attempting to detect a jack audio device Sep 16 15:20:04 volumio mpd[3780]: output: Successfully detected a jack audio device Sep 16 15:20:04 volumio mpd[3780]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 16 15:20:04 volumio mpd[3780]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 16 15:20:04 volumio mpd[3780]: zeroconf: No global port, disabling zeroconf Sep 16 15:20:04 volumio systemd[1]: Started Music Player Daemon. Sep 16 15:20:04 volumio sudo[3766]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:04 volumio wpa_supplicant[992]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Telecom-15382809" Sep 16 15:20:04 volumio wpa_supplicant[992]: wlan0: Trying to associate with SSID 'Telecom-15382809' Sep 16 15:20:04 volumio sudo[3829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-16 15:19 Sep 16 15:20:04 volumio sudo[3829]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:04 volumio sudo[3829]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:04 volumio volumio-remote-updater[484]: [2025-09-16 15:20:04] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 16 15:20:04 volumio volumio-remote-updater[484]: [2025-09-16 15:20:04] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 16 15:20:04 volumio ntfs-3g[3714]: Unmounting /dev/sda1 (musica) Sep 16 15:20:04 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 16 15:20:04 volumio systemd[1]: media-musica.mount: Succeeded. Sep 16 15:20:05 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 16 15:20:05 volumio systemd[1]: Started dynamicswap service. Sep 16 15:20:05 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 16 15:20:05 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 16 15:20:05 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Sep 16 15:20:05 volumio systemd[1]: Stopped Volumio Backend Module. Sep 16 15:20:05 volumio systemd[1]: Started Volumio Backend Module. Sep 16 15:20:05 volumio systemd[1]: Started dynamicswap service. Sep 16 15:20:05 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 16 15:20:07 volumio wpa_supplicant[992]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Sep 16 15:20:07 volumio wpa_supplicant[992]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Telecom-15382809" auth_failures=2 duration=23 reason=CONN_FAILED Sep 16 15:20:07 volumio volumio[3844]: info: ------------------------------------------- Sep 16 15:20:07 volumio volumio[3844]: info: ----- Volumio3 ---- Sep 16 15:20:07 volumio volumio[3844]: info: ------------------------------------------- Sep 16 15:20:07 volumio volumio[3844]: info: ----- System startup ---- Sep 16 15:20:07 volumio volumio[3844]: info: ------------------------------------------- Sep 16 15:20:07 volumio volumio[3844]: info: MYVOLUMIO Environment detected Sep 16 15:20:07 volumio volumio[3844]: info: Plugin folders cleanup Sep 16 15:20:07 volumio volumio[3844]: info: Scanning into folder /volumio/app/plugins/ Sep 16 15:20:07 volumio volumio[3844]: info: Scanning category audio_interface Sep 16 15:20:07 volumio volumio[3844]: info: Scanning category miscellanea Sep 16 15:20:08 volumio volumio[3844]: info: Scanning category music_service Sep 16 15:20:08 volumio volumio[3844]: info: Scanning category plugins.json Sep 16 15:20:08 volumio volumio[3844]: info: Scanning category system_controller Sep 16 15:20:08 volumio volumio[3844]: info: Scanning category user_interface Sep 16 15:20:08 volumio volumio[3844]: info: Scanning into folder /data/plugins/ Sep 16 15:20:08 volumio volumio[3844]: info: Scanning category music_service Sep 16 15:20:08 volumio volumio[3844]: info: Plugin folders cleanup completed Sep 16 15:20:08 volumio volumio[3844]: info: ------------------------------------------- Sep 16 15:20:08 volumio volumio[3844]: info: ----- Core plugins startup ---- Sep 16 15:20:08 volumio volumio[3844]: info: ------------------------------------------- Sep 16 15:20:08 volumio volumio[3844]: info: Loading plugins from folder /volumio/app/plugins/ Sep 16 15:20:08 volumio volumio[3844]: info: Adding plugin upnp to MyMusic Plugins Sep 16 15:20:08 volumio volumio[3844]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 16 15:20:08 volumio volumio[3844]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 16 15:20:08 volumio volumio[3844]: info: Loading plugins from folder /data/plugins/ Sep 16 15:20:08 volumio volumio[3844]: info: Loading plugin "system"... Sep 16 15:20:08 volumio volumio[3844]: info: Loading plugin "appearance"... Sep 16 15:20:09 volumio volumio[3844]: info: Loading plugin "network"... Sep 16 15:20:09 volumio volumio[3844]: info: Refreshing Cached IP Addresses Sep 16 15:20:09 volumio sudo[3869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 16 15:20:09 volumio sudo[3869]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:09 volumio sudo[3869]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:09 volumio volumio[3844]: info: Loading plugin "services"... Sep 16 15:20:09 volumio sudo[3871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 16 15:20:09 volumio sudo[3871]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:09 volumio volumio[3844]: info: Loading plugin "alsa_controller"... Sep 16 15:20:09 volumio sudo[3871]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:09 volumio sudo[3874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 16 15:20:09 volumio sudo[3874]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:09 volumio volumio[3844]: info: Loading plugin "wizard"... Sep 16 15:20:09 volumio volumio[3844]: info: Loading plugin "volumio_command_line_client"... Sep 16 15:20:09 volumio volumio[3844]: info: Loading plugin "upnp"... Sep 16 15:20:09 volumio volumio[3844]: info: [1758036009324] Starting Upmpd Daemon Sep 16 15:20:09 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:20:09 volumio volumio[3844]: info: Loading plugin "my_music"... Sep 16 15:20:09 volumio volumio[3844]: info: Loading plugin "mpd"... Sep 16 15:20:09 volumio volumio[3844]: info: Creating MPD Configuration file Sep 16 15:20:09 volumio volumio[3844]: info: Loading plugin "upnp_browser"... Sep 16 15:20:09 volumio sudo[3882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 15:20:09 volumio sudo[3882]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:09 volumio sudo[3882]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:09 volumio sudo[3884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 15:20:09 volumio sudo[3884]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:09 volumio systemd[1]: Stopping Music Player Daemon... Sep 16 15:20:09 volumio systemd[1]: mpd.service: Succeeded. Sep 16 15:20:09 volumio systemd[1]: Stopped Music Player Daemon. Sep 16 15:20:09 volumio systemd[1]: Starting Music Player Daemon... Sep 16 15:20:09 volumio volumio-remote-updater[484]: [2025-09-16 15:20:09] [connect] Successful connection Sep 16 15:20:10 volumio volumio[3844]: info: Loading plugin "networkfs"... Sep 16 15:20:10 volumio volumio[3844]: info: Starting Udev Watcher for removable devices Sep 16 15:20:10 volumio volumio[3844]: info: Ignoring mount for partition: boot Sep 16 15:20:10 volumio volumio[3844]: info: Ignoring mount for partition: volumio Sep 16 15:20:10 volumio volumio[3844]: info: Ignoring mount for partition: volumio_data Sep 16 15:20:10 volumio volumio[3844]: info: Mounting Device musica Sep 16 15:20:10 volumio sudo[3895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/musica -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 16 15:20:10 volumio sudo[3895]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:11 volumio mpd[3889]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 15:20:11 volumio mpd[3889]: config: Found database setting without music_directory - disabling database Sep 16 15:20:11 volumio mpd[3889]: output: No 'audio_output' defined in config file Sep 16 15:20:11 volumio mpd[3889]: output: Attempt to detect audio output device Sep 16 15:20:11 volumio mpd[3889]: output: Attempting to detect a alsa audio device Sep 16 15:20:11 volumio mpd[3889]: alsa_output: Error opening default ALSA device: Device or resource busy Sep 16 15:20:11 volumio mpd[3889]: output: Attempting to detect a oss audio device Sep 16 15:20:11 volumio mpd[3889]: oss_output: Error opening OSS device "/dev/dsp": No such file or directory Sep 16 15:20:11 volumio mpd[3889]: oss_output: Error opening OSS device "/dev/sound/dsp": No such file or directory Sep 16 15:20:11 volumio mpd[3889]: output: Attempting to detect a pulse audio device Sep 16 15:20:11 volumio mpd[3889]: output: Attempting to detect a jack audio device Sep 16 15:20:11 volumio mpd[3889]: output: Successfully detected a jack audio device Sep 16 15:20:11 volumio mpd[3889]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 16 15:20:11 volumio mpd[3889]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 16 15:20:11 volumio mpd[3889]: zeroconf: No global port, disabling zeroconf Sep 16 15:20:11 volumio systemd[1]: Started Music Player Daemon. Sep 16 15:20:11 volumio sudo[3884]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:11 volumio ntfs-3g[3901]: Version 2017.3.23AR.3 integrated FUSE 28 Sep 16 15:20:11 volumio ntfs-3g[3901]: Mounted /dev/sda1 (Read-Write, label "musica", NTFS 3.1) Sep 16 15:20:11 volumio ntfs-3g[3901]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 16 15:20:11 volumio ntfs-3g[3901]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Sep 16 15:20:11 volumio ntfs-3g[3901]: Global ownership and permissions enforced, configuration type 7 Sep 16 15:20:11 volumio sudo[3895]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:11 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:20:11 volumio volumio[3844]: info: Loading plugin "alarm-clock"... Sep 16 15:20:11 volumio volumio[3844]: info: Loading plugin "airplay_emulation"... Sep 16 15:20:11 volumio sudo[3874]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:11 volumio volumio[3844]: info: Starting Shairport Sync Sep 16 15:20:11 volumio volumio[3844]: info: Loading plugin "last_100"... Sep 16 15:20:11 volumio volumio[3844]: info: Loading plugin "webradio"... Sep 16 15:20:11 volumio volumio[3844]: info: Loading plugin "i2s_dacs"... Sep 16 15:20:11 volumio volumio[3844]: info: I2S DAC not set, start Auto-detection Sep 16 15:20:11 volumio volumio[3844]: info: Loading plugin "volumiodiscovery"... Sep 16 15:20:12 volumio volumio[3844]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 15:20:12 volumio volumio[3844]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:20:12 volumio volumio[3844]: *** WARNING *** For more information see Sep 16 15:20:12 volumio node[3844]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 15:20:12 volumio volumio[3844]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 15:20:12 volumio volumio[3844]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:20:12 volumio volumio[3844]: *** WARNING *** For more information see Sep 16 15:20:12 volumio node[3844]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:20:12 volumio node[3844]: *** WARNING *** For more information see Sep 16 15:20:12 volumio node[3844]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 15:20:12 volumio node[3844]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:20:12 volumio node[3844]: *** WARNING *** For more information see Sep 16 15:20:12 volumio volumio[3844]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 16 15:20:12 volumio volumio[3844]: info: Discovery: Started advertising with name: Volumio Sep 16 15:20:12 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:20:12 volumio volumio[3844]: info: Loading plugin "squeezelite_mc"... Sep 16 15:20:12 volumio volumio[3844]: info: Loading plugin "outputs"... Sep 16 15:20:12 volumio volumio[3844]: info: Loading plugin "albumart"... Sep 16 15:20:13 volumio volumio[3844]: info: Plugin example_plugin is not enabled Sep 16 15:20:13 volumio volumio[3844]: info: Loading plugin "inputs"... Sep 16 15:20:13 volumio volumio[3844]: info: Loading plugin "updater_comm"... Sep 16 15:20:13 volumio volumio[3844]: info: Plugin mpdemulation is not enabled Sep 16 15:20:13 volumio volumio[3844]: info: Loading plugin "rest_api"... Sep 16 15:20:13 volumio volumio[3844]: info: Loading plugin "websocket"... Sep 16 15:20:13 volumio volumio[3844]: info: Loading i18n strings for locale it Sep 16 15:20:13 volumio volumio[3844]: Updating browse sources language Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::initPlayerControls Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: Express server listening on port 3000 Sep 16 15:20:13 volumio volumio[3844]: [Metrics] WebUI: 6s 799.47ms Sep 16 15:20:13 volumio volumio[3844]: info: CoreStateMachine::resetVolumioState Sep 16 15:20:13 volumio volumio[3844]: info: CoreStateMachine::getcurrentVolume Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: Forking 3 albumart workers Sep 16 15:20:13 volumio mpd[3889]: client: [0] opened from local Sep 16 15:20:13 volumio volumio[3844]: info: MPD Permissions set Sep 16 15:20:13 volumio volumio-remote-updater[484]: [2025-09-16 15:20:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1758036009 101 Sep 16 15:20:13 volumio volumio[3844]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Sep 16 15:20:13 volumio mpd[3889]: exception: No database Sep 16 15:20:13 volumio mpd[3889]: exception: No database Sep 16 15:20:13 volumio volumio[3844]: info: MPD running with PID3889 Sep 16 15:20:13 volumio volumio[3844]: ,establishing connection Sep 16 15:20:13 volumio mpd[3889]: client: [1] opened from local Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:13 volumio volumio[3844]: error: updateQueue error: null Sep 16 15:20:13 volumio volumio[3844]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Sep 16 15:20:13 volumio volumio[3844]: info: CoreStateMachine::setRepeat false single undefined Sep 16 15:20:13 volumio volumio[3844]: info: CoreStateMachine::pushState Sep 16 15:20:13 volumio volumio[3844]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:13 volumio volumio[3844]: info: CoreStateMachine::setRandom false Sep 16 15:20:13 volumio volumio[3844]: info: CoreStateMachine::pushState Sep 16 15:20:13 volumio volumio[3844]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:20:13 volumio volumio[3844]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:14 volumio volumio[3844]: info: Setting Device type: Raspberry PI Sep 16 15:20:14 volumio volumio[3844]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:20:14 volumio volumio[3844]: info: CoreStateMachine::pushState Sep 16 15:20:14 volumio volumio[3844]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:14 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:20:14 volumio volumio[3844]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:14 volumio volumio[3844]: info: CoreStateMachine::updateTrackBlock Sep 16 15:20:14 volumio volumio[3844]: info: CorePlayQueue::getTrackBlock Sep 16 15:20:14 volumio volumio[3844]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:20:14 volumio volumio[3844]: error: updateQueue error: null Sep 16 15:20:14 volumio volumio[3844]: info: Completed loading Core Plugins Sep 16 15:20:14 volumio volumio[3844]: info: Preparing to generate the ALSA configuration file Sep 16 15:20:14 volumio volumio[3844]: info: Asound.conf file unchanged, so no further update is needed Sep 16 15:20:14 volumio volumio[3844]: info: Output device has changed, restarting MPD Sep 16 15:20:14 volumio volumio[3844]: info: Output device has changed, restarting Shairport Sync Sep 16 15:20:14 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:14 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:14 volumio sudo[3954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 15:20:14 volumio sudo[3954]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:14 volumio sudo[3953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 15:20:14 volumio sudo[3953]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:14 volumio sudo[3953]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:14 volumio systemd[1]: Stopping Music Player Daemon... Sep 16 15:20:14 volumio systemd[1]: mpd.service: Succeeded. Sep 16 15:20:14 volumio systemd[1]: Stopped Music Player Daemon. Sep 16 15:20:14 volumio systemd[1]: Starting Music Player Daemon... Sep 16 15:20:14 volumio volumio[3844]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:20:14 volumio volumio[3844]: info: ___________ START PLUGINS ___________ Sep 16 15:20:14 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:20:14 volumio volumio[3844]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:20:14 volumio volumio[3844]: info: [1758036014520] CoreMusicLibrary::Adding element Media Servers Sep 16 15:20:14 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:20:14 volumio volumio[3844]: Cannot find translation for sourceMedia Servers Sep 16 15:20:14 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:14 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:14 volumio volumio[3844]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:20:14 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:14 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:14 volumio volumio[3844]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:20:14 volumio volumio[3844]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:20:14 volumio volumio[3844]: info: [1758036014861] CoreMusicLibrary::Adding element Last_100 Sep 16 15:20:14 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:20:14 volumio volumio[3844]: Cannot find translation for sourceMedia Servers Sep 16 15:20:14 volumio volumio[3844]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:20:14 volumio volumio[3844]: info: [1758036014880] CoreMusicLibrary::Adding element Webradio Sep 16 15:20:14 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:20:14 volumio volumio[3844]: Cannot find translation for sourceMedia Servers Sep 16 15:20:14 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 15:20:14 volumio volumio[3844]: info: [squeezelite_mc] Starting proxy server... Sep 16 15:20:14 volumio volumio[3844]: info: Volumio Calling Home Sep 16 15:20:15 volumio volumio[3844]: Starting albumart workers Sep 16 15:20:15 volumio volumio[3844]: Starting albumart workers Sep 16 15:20:15 volumio volumio[3844]: info: [squeezelite_mc] Proxy server started on port 41497 Sep 16 15:20:15 volumio volumio[3844]: Starting albumart workers Sep 16 15:20:15 volumio volumio[3844]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:20:15 volumio volumio[3844]: Unhandled rejection Error: No sockets available, cannot start. Sep 16 15:20:15 volumio volumio[3844]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Sep 16 15:20:15 volumio volumio[3844]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Sep 16 15:20:15 volumio volumio[3844]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Sep 16 15:20:15 volumio volumio[3844]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Sep 16 15:20:15 volumio volumio[3844]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Sep 16 15:20:15 volumio volumio[3844]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Sep 16 15:20:15 volumio volumio[3844]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Sep 16 15:20:15 volumio volumio[3844]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Sep 16 15:20:15 volumio volumio[3844]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Sep 16 15:20:15 volumio volumio[3844]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Sep 16 15:20:15 volumio volumio[3844]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Sep 16 15:20:15 volumio volumio[3844]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 16 15:20:15 volumio volumio[3844]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 16 15:20:15 volumio volumio[3844]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Sep 16 15:20:15 volumio volumio[3844]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Sep 16 15:20:15 volumio volumio[3844]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 16 15:20:15 volumio volumio[3844]: info: MPD Permissions set Sep 16 15:20:15 volumio volumio[3844]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:20:15 volumio volumio[3844]: info: CoreStateMachine::pushState Sep 16 15:20:15 volumio volumio[3844]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:15 volumio volumio[3844]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:15 volumio volumio[3844]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Sep 16 15:20:15 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:15 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:15 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:15 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:15 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:15 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:15 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:16 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:16 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:16 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:16 volumio volumio[3844]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:20:16 volumio volumio[3844]: info: CoreStateMachine::pushState Sep 16 15:20:16 volumio volumio[3844]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:16 volumio volumio[3844]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:16 volumio volumio[3844]: info: [squeezelite_mc] Server discovery started Sep 16 15:20:16 volumio volumio[3844]: info: [squeezelite_mc] Player finder started Sep 16 15:20:16 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:16 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:16 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:16 volumio volumio[3844]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:20:16 volumio volumio[3844]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Sep 16 15:20:16 volumio volumio[3844]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 15:20:16 volumio volumio[3844]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 16 15:20:16 volumio volumio[3844]: at doSend (dgram.js:692:16) Sep 16 15:20:16 volumio volumio[3844]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Sep 16 15:20:16 volumio volumio[3844]: at afterDns (dgram.js:638:5) Sep 16 15:20:16 volumio volumio[3844]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 16 15:20:16 volumio volumio[3844]: errno: -101, Sep 16 15:20:16 volumio volumio[3844]: code: 'ENETUNREACH', Sep 16 15:20:16 volumio volumio[3844]: syscall: 'send', Sep 16 15:20:16 volumio volumio[3844]: address: '255.255.255.255', Sep 16 15:20:16 volumio volumio[3844]: port: 3483 Sep 16 15:20:16 volumio volumio[3844]: } Sep 16 15:20:16 volumio volumio[3844]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 15:20:16 volumio mpd[3968]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 15:20:16 volumio mpd[3968]: config: Found database setting without music_directory - disabling database Sep 16 15:20:16 volumio mpd[3968]: output: No 'audio_output' defined in config file Sep 16 15:20:16 volumio mpd[3968]: output: Attempt to detect audio output device Sep 16 15:20:16 volumio mpd[3968]: output: Attempting to detect a alsa audio device Sep 16 15:20:16 volumio mpd[3968]: alsa_output: Error opening default ALSA device: Device or resource busy Sep 16 15:20:16 volumio mpd[3968]: output: Attempting to detect a oss audio device Sep 16 15:20:16 volumio mpd[3968]: oss_output: Error opening OSS device "/dev/dsp": No such file or directory Sep 16 15:20:16 volumio mpd[3968]: oss_output: Error opening OSS device "/dev/sound/dsp": No such file or directory Sep 16 15:20:16 volumio mpd[3968]: output: Attempting to detect a pulse audio device Sep 16 15:20:16 volumio mpd[3968]: output: Attempting to detect a jack audio device Sep 16 15:20:16 volumio mpd[3968]: output: Successfully detected a jack audio device Sep 16 15:20:16 volumio mpd[3968]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 16 15:20:16 volumio mpd[3968]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 16 15:20:16 volumio mpd[3968]: zeroconf: No global port, disabling zeroconf Sep 16 15:20:16 volumio systemd[1]: Started Music Player Daemon. Sep 16 15:20:16 volumio sudo[3954]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:17 volumio sudo[4016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-16 15:19 Sep 16 15:20:17 volumio sudo[4016]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:17 volumio sudo[4016]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:17 volumio volumio-remote-updater[484]: [2025-09-16 15:20:17] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 16 15:20:17 volumio volumio-remote-updater[484]: [2025-09-16 15:20:17] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 16 15:20:17 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 16 15:20:17 volumio ntfs-3g[3901]: Unmounting /dev/sda1 (musica) Sep 16 15:20:17 volumio systemd[1]: media-musica.mount: Succeeded. Sep 16 15:20:17 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 16 15:20:17 volumio systemd[1]: Started dynamicswap service. Sep 16 15:20:17 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 16 15:20:17 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 16 15:20:17 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Sep 16 15:20:17 volumio systemd[1]: Started dynamicswap service. Sep 16 15:20:17 volumio systemd[1]: Stopped Volumio Backend Module. Sep 16 15:20:17 volumio systemd[1]: Started Volumio Backend Module. Sep 16 15:20:17 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 16 15:20:19 volumio volumio[4032]: info: ------------------------------------------- Sep 16 15:20:19 volumio volumio[4032]: info: ----- Volumio3 ---- Sep 16 15:20:19 volumio volumio[4032]: info: ------------------------------------------- Sep 16 15:20:19 volumio volumio[4032]: info: ----- System startup ---- Sep 16 15:20:19 volumio volumio[4032]: info: ------------------------------------------- Sep 16 15:20:20 volumio volumio[4032]: info: MYVOLUMIO Environment detected Sep 16 15:20:20 volumio volumio[4032]: info: Plugin folders cleanup Sep 16 15:20:20 volumio volumio[4032]: info: Scanning into folder /volumio/app/plugins/ Sep 16 15:20:20 volumio volumio[4032]: info: Scanning category audio_interface Sep 16 15:20:20 volumio volumio[4032]: info: Scanning category miscellanea Sep 16 15:20:20 volumio volumio[4032]: info: Scanning category music_service Sep 16 15:20:20 volumio volumio[4032]: info: Scanning category plugins.json Sep 16 15:20:20 volumio volumio[4032]: info: Scanning category system_controller Sep 16 15:20:20 volumio volumio[4032]: info: Scanning category user_interface Sep 16 15:20:20 volumio volumio[4032]: info: Scanning into folder /data/plugins/ Sep 16 15:20:20 volumio volumio[4032]: info: Scanning category music_service Sep 16 15:20:20 volumio volumio[4032]: info: Plugin folders cleanup completed Sep 16 15:20:20 volumio volumio[4032]: info: ------------------------------------------- Sep 16 15:20:20 volumio volumio[4032]: info: ----- Core plugins startup ---- Sep 16 15:20:20 volumio volumio[4032]: info: ------------------------------------------- Sep 16 15:20:20 volumio volumio[4032]: info: Loading plugins from folder /volumio/app/plugins/ Sep 16 15:20:20 volumio volumio[4032]: info: Adding plugin upnp to MyMusic Plugins Sep 16 15:20:20 volumio volumio[4032]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 16 15:20:20 volumio volumio[4032]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 16 15:20:20 volumio volumio[4032]: info: Loading plugins from folder /data/plugins/ Sep 16 15:20:20 volumio volumio[4032]: info: Loading plugin "system"... Sep 16 15:20:20 volumio volumio[4032]: info: Loading plugin "appearance"... Sep 16 15:20:21 volumio volumio[4032]: info: Loading plugin "network"... Sep 16 15:20:21 volumio volumio[4032]: info: Refreshing Cached IP Addresses Sep 16 15:20:21 volumio sudo[4056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 16 15:20:21 volumio sudo[4056]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:21 volumio sudo[4056]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:21 volumio volumio[4032]: info: Loading plugin "services"... Sep 16 15:20:21 volumio sudo[4058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 16 15:20:21 volumio volumio[4032]: info: Loading plugin "alsa_controller"... Sep 16 15:20:21 volumio sudo[4058]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:21 volumio sudo[4058]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:21 volumio sudo[4061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 16 15:20:21 volumio sudo[4061]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:21 volumio volumio[4032]: info: Loading plugin "wizard"... Sep 16 15:20:21 volumio volumio[4032]: info: Loading plugin "volumio_command_line_client"... Sep 16 15:20:21 volumio volumio[4032]: info: Loading plugin "upnp"... Sep 16 15:20:21 volumio volumio[4032]: info: [1758036021844] Starting Upmpd Daemon Sep 16 15:20:21 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:20:21 volumio volumio[4032]: info: Loading plugin "my_music"... Sep 16 15:20:21 volumio volumio[4032]: info: Loading plugin "mpd"... Sep 16 15:20:22 volumio volumio[4032]: info: Creating MPD Configuration file Sep 16 15:20:22 volumio volumio[4032]: info: Loading plugin "upnp_browser"... Sep 16 15:20:22 volumio sudo[4069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 15:20:22 volumio sudo[4069]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:22 volumio sudo[4069]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:22 volumio sudo[4071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 15:20:22 volumio sudo[4071]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:22 volumio systemd[1]: Stopping Music Player Daemon... Sep 16 15:20:22 volumio systemd[1]: mpd.service: Succeeded. Sep 16 15:20:22 volumio systemd[1]: Stopped Music Player Daemon. Sep 16 15:20:22 volumio volumio-remote-updater[484]: [2025-09-16 15:20:22] [connect] Successful connection Sep 16 15:20:22 volumio systemd[1]: Starting Music Player Daemon... Sep 16 15:20:22 volumio volumio[4032]: info: Loading plugin "networkfs"... Sep 16 15:20:22 volumio volumio[4032]: info: Starting Udev Watcher for removable devices Sep 16 15:20:23 volumio volumio[4032]: info: Ignoring mount for partition: boot Sep 16 15:20:23 volumio volumio[4032]: info: Ignoring mount for partition: volumio Sep 16 15:20:23 volumio volumio[4032]: info: Ignoring mount for partition: volumio_data Sep 16 15:20:23 volumio volumio[4032]: info: Mounting Device musica Sep 16 15:20:23 volumio sudo[4082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/musica -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 16 15:20:23 volumio sudo[4082]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:23 volumio mpd[4076]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 15:20:23 volumio mpd[4076]: config: Found database setting without music_directory - disabling database Sep 16 15:20:23 volumio mpd[4076]: output: No 'audio_output' defined in config file Sep 16 15:20:23 volumio mpd[4076]: output: Attempt to detect audio output device Sep 16 15:20:23 volumio mpd[4076]: output: Attempting to detect a alsa audio device Sep 16 15:20:23 volumio mpd[4076]: alsa_output: Error opening default ALSA device: Device or resource busy Sep 16 15:20:23 volumio mpd[4076]: output: Attempting to detect a oss audio device Sep 16 15:20:23 volumio mpd[4076]: oss_output: Error opening OSS device "/dev/dsp": No such file or directory Sep 16 15:20:23 volumio mpd[4076]: oss_output: Error opening OSS device "/dev/sound/dsp": No such file or directory Sep 16 15:20:23 volumio mpd[4076]: output: Attempting to detect a pulse audio device Sep 16 15:20:23 volumio mpd[4076]: output: Attempting to detect a jack audio device Sep 16 15:20:23 volumio mpd[4076]: output: Successfully detected a jack audio device Sep 16 15:20:23 volumio mpd[4076]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 16 15:20:23 volumio mpd[4076]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 16 15:20:23 volumio mpd[4076]: zeroconf: No global port, disabling zeroconf Sep 16 15:20:23 volumio systemd[1]: Started Music Player Daemon. Sep 16 15:20:23 volumio sudo[4071]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:24 volumio ntfs-3g[4089]: Version 2017.3.23AR.3 integrated FUSE 28 Sep 16 15:20:24 volumio ntfs-3g[4089]: Mounted /dev/sda1 (Read-Write, label "musica", NTFS 3.1) Sep 16 15:20:24 volumio ntfs-3g[4089]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 16 15:20:24 volumio ntfs-3g[4089]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Sep 16 15:20:24 volumio ntfs-3g[4089]: Global ownership and permissions enforced, configuration type 7 Sep 16 15:20:24 volumio sudo[4082]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:24 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:20:24 volumio volumio[4032]: info: Loading plugin "alarm-clock"... Sep 16 15:20:24 volumio sudo[4061]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:24 volumio volumio[4032]: info: Loading plugin "airplay_emulation"... Sep 16 15:20:24 volumio volumio[4032]: info: Starting Shairport Sync Sep 16 15:20:24 volumio volumio[4032]: info: Loading plugin "last_100"... Sep 16 15:20:24 volumio volumio[4032]: info: Loading plugin "webradio"... Sep 16 15:20:24 volumio volumio[4032]: info: Loading plugin "i2s_dacs"... Sep 16 15:20:24 volumio volumio[4032]: info: I2S DAC not set, start Auto-detection Sep 16 15:20:24 volumio volumio[4032]: info: Loading plugin "volumiodiscovery"... Sep 16 15:20:24 volumio volumio[4032]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 15:20:24 volumio volumio[4032]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:20:24 volumio volumio[4032]: *** WARNING *** For more information see Sep 16 15:20:24 volumio node[4032]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 15:20:24 volumio volumio[4032]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 15:20:24 volumio volumio[4032]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:20:24 volumio volumio[4032]: *** WARNING *** For more information see Sep 16 15:20:24 volumio node[4032]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:20:24 volumio node[4032]: *** WARNING *** For more information see Sep 16 15:20:24 volumio node[4032]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 15:20:24 volumio node[4032]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:20:24 volumio node[4032]: *** WARNING *** For more information see Sep 16 15:20:24 volumio volumio[4032]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 16 15:20:24 volumio volumio[4032]: info: Discovery: Started advertising with name: Volumio Sep 16 15:20:24 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:20:24 volumio volumio[4032]: info: Loading plugin "squeezelite_mc"... Sep 16 15:20:25 volumio volumio[4032]: info: Loading plugin "outputs"... Sep 16 15:20:25 volumio volumio[4032]: info: Loading plugin "albumart"... Sep 16 15:20:25 volumio volumio[4032]: info: Plugin example_plugin is not enabled Sep 16 15:20:25 volumio volumio[4032]: info: Loading plugin "inputs"... Sep 16 15:20:25 volumio volumio[4032]: info: Loading plugin "updater_comm"... Sep 16 15:20:25 volumio volumio[4032]: info: Plugin mpdemulation is not enabled Sep 16 15:20:25 volumio volumio[4032]: info: Loading plugin "rest_api"... Sep 16 15:20:25 volumio volumio[4032]: info: Loading plugin "websocket"... Sep 16 15:20:25 volumio volumio[4032]: info: Loading i18n strings for locale it Sep 16 15:20:25 volumio volumio[4032]: Updating browse sources language Sep 16 15:20:25 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:20:25 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:20:25 volumio volumio[4032]: info: CoreCommandRouter::initPlayerControls Sep 16 15:20:25 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:25 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:25 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:25 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:25 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:25 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:25 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:25 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:25 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:20:25 volumio volumio[4032]: Express server listening on port 3000 Sep 16 15:20:25 volumio volumio[4032]: [Metrics] WebUI: 6s 819.72ms Sep 16 15:20:25 volumio volumio[4032]: info: CoreStateMachine::resetVolumioState Sep 16 15:20:25 volumio volumio[4032]: info: CoreStateMachine::getcurrentVolume Sep 16 15:20:25 volumio volumio[4032]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:20:25 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:25 volumio mpd[4076]: client: [0] opened from local Sep 16 15:20:25 volumio volumio[4032]: Forking 3 albumart workers Sep 16 15:20:25 volumio volumio[4032]: info: MPD Permissions set Sep 16 15:20:25 volumio volumio-remote-updater[484]: [2025-09-16 15:20:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1758036022 101 Sep 16 15:20:26 volumio volumio[4032]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Sep 16 15:20:26 volumio mpd[4076]: exception: No database Sep 16 15:20:26 volumio mpd[4076]: exception: No database Sep 16 15:20:26 volumio volumio[4032]: info: MPD running with PID4076 Sep 16 15:20:26 volumio volumio[4032]: ,establishing connection Sep 16 15:20:26 volumio mpd[4076]: client: [1] opened from local Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:26 volumio volumio[4032]: error: updateQueue error: null Sep 16 15:20:26 volumio volumio[4032]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Sep 16 15:20:26 volumio volumio[4032]: info: CoreStateMachine::setRepeat false single undefined Sep 16 15:20:26 volumio volumio[4032]: info: CoreStateMachine::pushState Sep 16 15:20:26 volumio volumio[4032]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:26 volumio volumio[4032]: info: CoreStateMachine::setRandom false Sep 16 15:20:26 volumio volumio[4032]: info: CoreStateMachine::pushState Sep 16 15:20:26 volumio volumio[4032]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:26 volumio volumio[4032]: info: Setting Device type: Raspberry PI Sep 16 15:20:26 volumio volumio[4032]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:20:26 volumio volumio[4032]: info: CoreStateMachine::pushState Sep 16 15:20:26 volumio volumio[4032]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:26 volumio volumio[4032]: info: CoreStateMachine::updateTrackBlock Sep 16 15:20:26 volumio volumio[4032]: info: CorePlayQueue::getTrackBlock Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:20:26 volumio volumio[4032]: error: updateQueue error: null Sep 16 15:20:26 volumio volumio[4032]: info: Completed loading Core Plugins Sep 16 15:20:26 volumio volumio[4032]: info: Preparing to generate the ALSA configuration file Sep 16 15:20:26 volumio volumio[4032]: info: Asound.conf file unchanged, so no further update is needed Sep 16 15:20:26 volumio volumio[4032]: info: Output device has changed, restarting MPD Sep 16 15:20:26 volumio volumio[4032]: info: Output device has changed, restarting Shairport Sync Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:26 volumio sudo[4139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 15:20:26 volumio sudo[4139]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:26 volumio sudo[4139]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:26 volumio sudo[4140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 15:20:26 volumio sudo[4140]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:26 volumio systemd[1]: Stopping Music Player Daemon... Sep 16 15:20:26 volumio systemd[1]: mpd.service: Succeeded. Sep 16 15:20:26 volumio volumio[4032]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:20:26 volumio systemd[1]: Stopped Music Player Daemon. Sep 16 15:20:26 volumio volumio[4032]: info: ___________ START PLUGINS ___________ Sep 16 15:20:26 volumio systemd[1]: Starting Music Player Daemon... Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:20:26 volumio volumio[4032]: info: [1758036026753] CoreMusicLibrary::Adding element Media Servers Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:20:26 volumio volumio[4032]: Cannot find translation for sourceMedia Servers Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:26 volumio volumio[4032]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:26 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:27 volumio volumio[4032]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:20:27 volumio volumio[4032]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:20:27 volumio volumio[4032]: info: [1758036027105] CoreMusicLibrary::Adding element Last_100 Sep 16 15:20:27 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:20:27 volumio volumio[4032]: Cannot find translation for sourceMedia Servers Sep 16 15:20:27 volumio volumio[4032]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:20:27 volumio volumio[4032]: info: [1758036027113] CoreMusicLibrary::Adding element Webradio Sep 16 15:20:27 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:20:27 volumio volumio[4032]: Cannot find translation for sourceMedia Servers Sep 16 15:20:27 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 15:20:27 volumio volumio[4032]: info: [squeezelite_mc] Starting proxy server... Sep 16 15:20:27 volumio volumio[4032]: info: Volumio Calling Home Sep 16 15:20:27 volumio volumio[4032]: Starting albumart workers Sep 16 15:20:27 volumio volumio[4032]: info: [squeezelite_mc] Proxy server started on port 40395 Sep 16 15:20:27 volumio volumio[4032]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:20:27 volumio volumio[4032]: Unhandled rejection Error: No sockets available, cannot start. Sep 16 15:20:27 volumio volumio[4032]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Sep 16 15:20:27 volumio volumio[4032]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Sep 16 15:20:27 volumio volumio[4032]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Sep 16 15:20:27 volumio volumio[4032]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Sep 16 15:20:27 volumio volumio[4032]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Sep 16 15:20:27 volumio volumio[4032]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Sep 16 15:20:27 volumio volumio[4032]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Sep 16 15:20:27 volumio volumio[4032]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Sep 16 15:20:27 volumio volumio[4032]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Sep 16 15:20:27 volumio volumio[4032]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Sep 16 15:20:27 volumio volumio[4032]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Sep 16 15:20:27 volumio volumio[4032]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 16 15:20:27 volumio volumio[4032]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 16 15:20:27 volumio volumio[4032]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Sep 16 15:20:27 volumio volumio[4032]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Sep 16 15:20:27 volumio volumio[4032]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 16 15:20:27 volumio volumio[4032]: info: MPD Permissions set Sep 16 15:20:27 volumio volumio[4032]: Starting albumart workers Sep 16 15:20:27 volumio volumio[4032]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Sep 16 15:20:27 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:27 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:27 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:27 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:27 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:27 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:27 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:27 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:28 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:28 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:28 volumio volumio[4032]: Starting albumart workers Sep 16 15:20:28 volumio volumio[4032]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:20:28 volumio volumio[4032]: info: CoreStateMachine::pushState Sep 16 15:20:28 volumio volumio[4032]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:28 volumio volumio[4032]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:28 volumio volumio[4032]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:20:28 volumio volumio[4032]: info: CoreStateMachine::pushState Sep 16 15:20:28 volumio volumio[4032]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:28 volumio volumio[4032]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:28 volumio volumio[4032]: info: [squeezelite_mc] Server discovery started Sep 16 15:20:28 volumio volumio[4032]: info: [squeezelite_mc] Player finder started Sep 16 15:20:28 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:28 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:28 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:28 volumio volumio[4032]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:20:28 volumio volumio[4032]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Sep 16 15:20:28 volumio volumio[4032]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 15:20:28 volumio volumio[4032]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 16 15:20:28 volumio volumio[4032]: at doSend (dgram.js:692:16) Sep 16 15:20:28 volumio volumio[4032]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Sep 16 15:20:28 volumio volumio[4032]: at afterDns (dgram.js:638:5) Sep 16 15:20:28 volumio volumio[4032]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 16 15:20:28 volumio volumio[4032]: errno: -101, Sep 16 15:20:28 volumio volumio[4032]: code: 'ENETUNREACH', Sep 16 15:20:28 volumio volumio[4032]: syscall: 'send', Sep 16 15:20:28 volumio volumio[4032]: address: '255.255.255.255', Sep 16 15:20:28 volumio volumio[4032]: port: 3483 Sep 16 15:20:28 volumio volumio[4032]: } Sep 16 15:20:28 volumio volumio[4032]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 15:20:28 volumio mpd[4154]: Sep 16 15:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 15:20:29 volumio mpd[4154]: Sep 16 15:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 16 15:20:29 volumio mpd[4154]: Sep 16 15:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 16 15:20:29 volumio systemd[1]: Started Music Player Daemon. Sep 16 15:20:29 volumio sudo[4140]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:29 volumio sudo[4202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-16 15:19 Sep 16 15:20:29 volumio sudo[4202]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:29 volumio sudo[4202]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:29 volumio volumio-remote-updater[484]: [2025-09-16 15:20:29] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 16 15:20:29 volumio volumio-remote-updater[484]: [2025-09-16 15:20:29] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 16 15:20:29 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 16 15:20:29 volumio ntfs-3g[4089]: Unmounting /dev/sda1 (musica) Sep 16 15:20:29 volumio systemd[1]: media-musica.mount: Succeeded. Sep 16 15:20:29 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 16 15:20:29 volumio systemd[1]: Started dynamicswap service. Sep 16 15:20:29 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 16 15:20:29 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 16 15:20:29 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Sep 16 15:20:29 volumio systemd[1]: Started dynamicswap service. Sep 16 15:20:29 volumio systemd[1]: Stopped Volumio Backend Module. Sep 16 15:20:29 volumio systemd[1]: Started Volumio Backend Module. Sep 16 15:20:29 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 16 15:20:31 volumio volumio[4218]: info: ------------------------------------------- Sep 16 15:20:31 volumio volumio[4218]: info: ----- Volumio3 ---- Sep 16 15:20:31 volumio volumio[4218]: info: ------------------------------------------- Sep 16 15:20:31 volumio volumio[4218]: info: ----- System startup ---- Sep 16 15:20:31 volumio volumio[4218]: info: ------------------------------------------- Sep 16 15:20:32 volumio volumio[4218]: info: MYVOLUMIO Environment detected Sep 16 15:20:32 volumio volumio[4218]: info: Plugin folders cleanup Sep 16 15:20:32 volumio volumio[4218]: info: Scanning into folder /volumio/app/plugins/ Sep 16 15:20:32 volumio volumio[4218]: info: Scanning category audio_interface Sep 16 15:20:32 volumio volumio[4218]: info: Scanning category miscellanea Sep 16 15:20:32 volumio volumio[4218]: info: Scanning category music_service Sep 16 15:20:32 volumio volumio[4218]: info: Scanning category plugins.json Sep 16 15:20:32 volumio volumio[4218]: info: Scanning category system_controller Sep 16 15:20:32 volumio volumio[4218]: info: Scanning category user_interface Sep 16 15:20:32 volumio volumio[4218]: info: Scanning into folder /data/plugins/ Sep 16 15:20:32 volumio volumio[4218]: info: Scanning category music_service Sep 16 15:20:32 volumio volumio[4218]: info: Plugin folders cleanup completed Sep 16 15:20:32 volumio volumio[4218]: info: ------------------------------------------- Sep 16 15:20:32 volumio volumio[4218]: info: ----- Core plugins startup ---- Sep 16 15:20:32 volumio volumio[4218]: info: ------------------------------------------- Sep 16 15:20:32 volumio volumio[4218]: info: Loading plugins from folder /volumio/app/plugins/ Sep 16 15:20:32 volumio volumio[4218]: info: Adding plugin upnp to MyMusic Plugins Sep 16 15:20:32 volumio volumio[4218]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 16 15:20:32 volumio volumio[4218]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 16 15:20:32 volumio volumio[4218]: info: Loading plugins from folder /data/plugins/ Sep 16 15:20:32 volumio volumio[4218]: info: Loading plugin "system"... Sep 16 15:20:32 volumio volumio[4218]: info: Loading plugin "appearance"... Sep 16 15:20:33 volumio wpa_supplicant[992]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Telecom-15382809" Sep 16 15:20:33 volumio wpa_supplicant[992]: wlan0: Trying to associate with SSID 'Telecom-15382809' Sep 16 15:20:33 volumio volumio[4218]: info: Loading plugin "network"... Sep 16 15:20:33 volumio volumio[4218]: info: Refreshing Cached IP Addresses Sep 16 15:20:33 volumio sudo[4241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 16 15:20:33 volumio sudo[4241]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:33 volumio sudo[4241]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:33 volumio sudo[4243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 16 15:20:33 volumio sudo[4243]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:33 volumio volumio[4218]: info: Loading plugin "services"... Sep 16 15:20:33 volumio sudo[4243]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:33 volumio volumio[4218]: info: Loading plugin "alsa_controller"... Sep 16 15:20:33 volumio sudo[4247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 16 15:20:33 volumio sudo[4247]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:33 volumio sudo[4247]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:33 volumio kernel: ieee80211 phy0: brcmf_cfg80211_scan: Connecting: status (3) Sep 16 15:20:33 volumio volumio[4218]: info: Loading plugin "wizard"... Sep 16 15:20:33 volumio volumio[4218]: info: Loading plugin "volumio_command_line_client"... Sep 16 15:20:33 volumio volumio[4218]: info: Loading plugin "upnp"... Sep 16 15:20:33 volumio volumio[4218]: info: [1758036033993] Starting Upmpd Daemon Sep 16 15:20:33 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:20:33 volumio volumio[4218]: info: Loading plugin "my_music"... Sep 16 15:20:34 volumio volumio[4218]: info: Loading plugin "mpd"... Sep 16 15:20:34 volumio volumio[4218]: info: Creating MPD Configuration file Sep 16 15:20:34 volumio volumio[4218]: info: Loading plugin "upnp_browser"... Sep 16 15:20:34 volumio sudo[4254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 15:20:34 volumio sudo[4254]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:34 volumio sudo[4254]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:34 volumio sudo[4256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 15:20:34 volumio sudo[4256]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:34 volumio systemd[1]: Stopping Music Player Daemon... Sep 16 15:20:34 volumio systemd[1]: mpd.service: Succeeded. Sep 16 15:20:34 volumio systemd[1]: Stopped Music Player Daemon. Sep 16 15:20:34 volumio systemd[1]: Starting Music Player Daemon... Sep 16 15:20:34 volumio volumio-remote-updater[484]: [2025-09-16 15:20:34] [connect] Successful connection Sep 16 15:20:34 volumio volumio[4218]: info: Loading plugin "networkfs"... Sep 16 15:20:35 volumio volumio[4218]: info: Starting Udev Watcher for removable devices Sep 16 15:20:35 volumio volumio[4218]: info: Ignoring mount for partition: boot Sep 16 15:20:35 volumio volumio[4218]: info: Ignoring mount for partition: volumio Sep 16 15:20:35 volumio volumio[4218]: info: Ignoring mount for partition: volumio_data Sep 16 15:20:35 volumio volumio[4218]: info: Mounting Device musica Sep 16 15:20:35 volumio sudo[4267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/musica -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 16 15:20:35 volumio sudo[4267]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:35 volumio wpa_supplicant[992]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Sep 16 15:20:35 volumio wpa_supplicant[992]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Telecom-15382809" auth_failures=3 duration=46 reason=CONN_FAILED Sep 16 15:20:36 volumio mpd[4261]: Sep 16 15:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 15:20:36 volumio ntfs-3g[4271]: Version 2017.3.23AR.3 integrated FUSE 28 Sep 16 15:20:36 volumio ntfs-3g[4271]: Mounted /dev/sda1 (Read-Write, label "musica", NTFS 3.1) Sep 16 15:20:36 volumio ntfs-3g[4271]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 16 15:20:36 volumio ntfs-3g[4271]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Sep 16 15:20:36 volumio ntfs-3g[4271]: Global ownership and permissions enforced, configuration type 7 Sep 16 15:20:36 volumio sudo[4267]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:36 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:20:36 volumio volumio[4218]: info: Loading plugin "alarm-clock"... Sep 16 15:20:36 volumio volumio[4218]: info: Loading plugin "airplay_emulation"... Sep 16 15:20:36 volumio mpd[4261]: Sep 16 15:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 16 15:20:36 volumio mpd[4261]: Sep 16 15:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 16 15:20:36 volumio volumio[4218]: info: Starting Shairport Sync Sep 16 15:20:36 volumio volumio[4218]: info: Loading plugin "last_100"... Sep 16 15:20:36 volumio systemd[1]: Started Music Player Daemon. Sep 16 15:20:36 volumio sudo[4256]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:36 volumio volumio[4218]: info: Loading plugin "webradio"... Sep 16 15:20:36 volumio volumio[4218]: info: Loading plugin "i2s_dacs"... Sep 16 15:20:36 volumio volumio[4218]: info: I2S DAC not set, start Auto-detection Sep 16 15:20:36 volumio volumio[4218]: info: Loading plugin "volumiodiscovery"... Sep 16 15:20:36 volumio volumio[4218]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 15:20:36 volumio volumio[4218]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:20:36 volumio volumio[4218]: *** WARNING *** For more information see Sep 16 15:20:36 volumio node[4218]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 15:20:36 volumio volumio[4218]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 15:20:36 volumio volumio[4218]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:20:36 volumio volumio[4218]: *** WARNING *** For more information see Sep 16 15:20:36 volumio node[4218]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:20:36 volumio node[4218]: *** WARNING *** For more information see Sep 16 15:20:36 volumio node[4218]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 15:20:36 volumio node[4218]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:20:36 volumio node[4218]: *** WARNING *** For more information see Sep 16 15:20:36 volumio volumio[4218]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 16 15:20:36 volumio volumio[4218]: info: Discovery: Started advertising with name: Volumio Sep 16 15:20:36 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:20:36 volumio volumio[4218]: info: Loading plugin "squeezelite_mc"... Sep 16 15:20:37 volumio volumio[4218]: info: Loading plugin "outputs"... Sep 16 15:20:37 volumio volumio[4218]: info: Loading plugin "albumart"... Sep 16 15:20:37 volumio volumio[4218]: info: Plugin example_plugin is not enabled Sep 16 15:20:37 volumio volumio[4218]: info: Loading plugin "inputs"... Sep 16 15:20:37 volumio volumio[4218]: info: Loading plugin "updater_comm"... Sep 16 15:20:37 volumio volumio[4218]: info: Plugin mpdemulation is not enabled Sep 16 15:20:37 volumio volumio[4218]: info: Loading plugin "rest_api"... Sep 16 15:20:37 volumio volumio[4218]: info: Loading plugin "websocket"... Sep 16 15:20:37 volumio volumio[4218]: info: Loading i18n strings for locale it Sep 16 15:20:37 volumio volumio[4218]: Updating browse sources language Sep 16 15:20:37 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:20:37 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:20:37 volumio volumio[4218]: info: CoreCommandRouter::initPlayerControls Sep 16 15:20:37 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:37 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:37 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:37 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:37 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:37 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:37 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:37 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:37 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:20:37 volumio volumio[4218]: Express server listening on port 3000 Sep 16 15:20:37 volumio volumio[4218]: [Metrics] WebUI: 6s 723.37ms Sep 16 15:20:38 volumio volumio[4218]: info: CoreStateMachine::resetVolumioState Sep 16 15:20:38 volumio volumio[4218]: info: CoreStateMachine::getcurrentVolume Sep 16 15:20:38 volumio volumio[4218]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:20:38 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:38 volumio volumio[4218]: Forking 3 albumart workers Sep 16 15:20:38 volumio volumio[4218]: info: MPD Permissions set Sep 16 15:20:38 volumio volumio[4218]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 16 15:20:38 volumio volumio[4218]: wlan0 Interface doesn't support scanning : Resource temporarily unavailable Sep 16 15:20:38 volumio volumio[4218]: info: Cannot use regular scanning, forcing with ap-force Sep 16 15:20:38 volumio sudo[4310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 16 15:20:38 volumio sudo[4310]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:39 volumio volumio[4218]: Starting albumart workers Sep 16 15:20:39 volumio volumio[4218]: Starting albumart workers Sep 16 15:20:39 volumio volumio[4218]: Starting albumart workers Sep 16 15:20:40 volumio sudo[4310]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:40 volumio volumio[4218]: info: MPD running with PID4261 Sep 16 15:20:40 volumio volumio[4218]: ,establishing connection Sep 16 15:20:41 volumio volumio[4218]: error: MPD error: The expression evaluated to a falsy value: Sep 16 15:20:41 volumio volumio[4218]: assert.ok(self.idling) Sep 16 15:20:41 volumio volumio[4218]: error: MPD error: The expression evaluated to a falsy value: Sep 16 15:20:41 volumio volumio[4218]: assert.ok(self.idling) Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:41 volumio volumio[4218]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:20:41 volumio volumio[4218]: info: CoreStateMachine::pushState Sep 16 15:20:41 volumio volumio[4218]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:41 volumio volumio[4218]: info: CoreStateMachine::updateTrackBlock Sep 16 15:20:41 volumio volumio[4218]: info: CorePlayQueue::getTrackBlock Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:20:41 volumio volumio[4218]: info: CoreStateMachine::setRepeat false single undefined Sep 16 15:20:41 volumio volumio[4218]: info: CoreStateMachine::pushState Sep 16 15:20:41 volumio volumio[4218]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:41 volumio volumio[4218]: info: CoreStateMachine::setRandom false Sep 16 15:20:41 volumio volumio[4218]: info: CoreStateMachine::pushState Sep 16 15:20:41 volumio volumio[4218]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:41 volumio volumio[4218]: info: Setting Device type: Raspberry PI Sep 16 15:20:41 volumio volumio[4218]: error: updateQueue error: null Sep 16 15:20:41 volumio volumio[4218]: info: Completed loading Core Plugins Sep 16 15:20:41 volumio volumio[4218]: info: Preparing to generate the ALSA configuration file Sep 16 15:20:41 volumio volumio[4218]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:20:41 volumio volumio[4218]: info: CoreStateMachine::pushState Sep 16 15:20:41 volumio volumio[4218]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:41 volumio volumio[4218]: info: Asound.conf file unchanged, so no further update is needed Sep 16 15:20:41 volumio volumio[4218]: info: Output device has changed, restarting MPD Sep 16 15:20:41 volumio sudo[4351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 15:20:41 volumio sudo[4351]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:41 volumio volumio[4218]: info: Output device has changed, restarting Shairport Sync Sep 16 15:20:41 volumio sudo[4351]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:41 volumio sudo[4353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 15:20:41 volumio sudo[4353]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:41 volumio systemd[1]: Stopping Music Player Daemon... Sep 16 15:20:41 volumio volumio[4218]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:20:41 volumio volumio[4218]: info: ___________ START PLUGINS ___________ Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:20:41 volumio volumio[4218]: info: [1758036041405] CoreMusicLibrary::Adding element Media Servers Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:20:41 volumio volumio[4218]: Cannot find translation for sourceMedia Servers Sep 16 15:20:41 volumio systemd[1]: mpd.service: Succeeded. Sep 16 15:20:41 volumio systemd[1]: Stopped Music Player Daemon. Sep 16 15:20:41 volumio systemd[1]: Starting Music Player Daemon... Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:41 volumio volumio[4218]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:41 volumio volumio[4218]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:20:41 volumio volumio[4218]: info: [1758036041580] CoreMusicLibrary::Adding element Last_100 Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:20:41 volumio volumio[4218]: Cannot find translation for sourceMedia Servers Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:20:41 volumio volumio[4218]: info: [1758036041587] CoreMusicLibrary::Adding element Webradio Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:20:41 volumio volumio[4218]: Cannot find translation for sourceMedia Servers Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 15:20:41 volumio volumio[4218]: info: [squeezelite_mc] Starting proxy server... Sep 16 15:20:41 volumio volumio[4218]: info: Volumio Calling Home Sep 16 15:20:41 volumio volumio[4218]: info: [squeezelite_mc] Proxy server started on port 35033 Sep 16 15:20:41 volumio volumio[4218]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:20:41 volumio volumio[4218]: Unhandled rejection Error: No sockets available, cannot start. Sep 16 15:20:41 volumio volumio[4218]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Sep 16 15:20:41 volumio volumio[4218]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Sep 16 15:20:41 volumio volumio[4218]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Sep 16 15:20:41 volumio volumio[4218]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Sep 16 15:20:41 volumio volumio[4218]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Sep 16 15:20:41 volumio volumio[4218]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Sep 16 15:20:41 volumio volumio[4218]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Sep 16 15:20:41 volumio volumio[4218]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Sep 16 15:20:41 volumio volumio[4218]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Sep 16 15:20:41 volumio volumio[4218]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Sep 16 15:20:41 volumio volumio[4218]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Sep 16 15:20:41 volumio volumio[4218]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 16 15:20:41 volumio volumio[4218]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 16 15:20:41 volumio volumio[4218]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Sep 16 15:20:41 volumio volumio[4218]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Sep 16 15:20:41 volumio volumio[4218]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 16 15:20:41 volumio volumio[4218]: info: MPD Permissions set Sep 16 15:20:42 volumio volumio[4218]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Sep 16 15:20:42 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:42 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:42 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:42 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:42 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:42 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:42 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:42 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:42 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:42 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:42 volumio volumio[4218]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:20:42 volumio volumio[4218]: info: CoreStateMachine::pushState Sep 16 15:20:42 volumio volumio[4218]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:42 volumio volumio[4218]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:42 volumio volumio[4218]: info: [squeezelite_mc] Server discovery started Sep 16 15:20:42 volumio volumio[4218]: info: [squeezelite_mc] Player finder started Sep 16 15:20:42 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:42 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:42 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:42 volumio volumio[4218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:20:42 volumio volumio[4218]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Sep 16 15:20:42 volumio volumio[4218]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 15:20:42 volumio volumio[4218]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 16 15:20:42 volumio volumio[4218]: at doSend (dgram.js:692:16) Sep 16 15:20:42 volumio volumio[4218]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Sep 16 15:20:42 volumio volumio[4218]: at afterDns (dgram.js:638:5) Sep 16 15:20:42 volumio volumio[4218]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 16 15:20:42 volumio volumio[4218]: errno: -101, Sep 16 15:20:42 volumio volumio[4218]: code: 'ENETUNREACH', Sep 16 15:20:42 volumio volumio[4218]: syscall: 'send', Sep 16 15:20:42 volumio volumio[4218]: address: '255.255.255.255', Sep 16 15:20:42 volumio volumio[4218]: port: 3483 Sep 16 15:20:42 volumio volumio[4218]: } Sep 16 15:20:42 volumio volumio[4218]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 15:20:42 volumio sudo[4388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-16 15:19 Sep 16 15:20:42 volumio sudo[4388]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:42 volumio mpd[4370]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 15:20:42 volumio mpd[4370]: config: Found database setting without music_directory - disabling database Sep 16 15:20:42 volumio mpd[4370]: output: No 'audio_output' defined in config file Sep 16 15:20:42 volumio mpd[4370]: output: Attempt to detect audio output device Sep 16 15:20:42 volumio mpd[4370]: output: Attempting to detect a alsa audio device Sep 16 15:20:42 volumio mpd[4370]: alsa_output: Error opening default ALSA device: Device or resource busy Sep 16 15:20:42 volumio mpd[4370]: output: Attempting to detect a oss audio device Sep 16 15:20:42 volumio mpd[4370]: oss_output: Error opening OSS device "/dev/dsp": No such file or directory Sep 16 15:20:42 volumio mpd[4370]: oss_output: Error opening OSS device "/dev/sound/dsp": No such file or directory Sep 16 15:20:42 volumio mpd[4370]: output: Attempting to detect a pulse audio device Sep 16 15:20:42 volumio mpd[4370]: output: Attempting to detect a jack audio device Sep 16 15:20:42 volumio mpd[4370]: output: Successfully detected a jack audio device Sep 16 15:20:42 volumio mpd[4370]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 16 15:20:42 volumio mpd[4370]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 16 15:20:42 volumio mpd[4370]: zeroconf: No global port, disabling zeroconf Sep 16 15:20:42 volumio systemd[1]: Started Music Player Daemon. Sep 16 15:20:42 volumio sudo[4353]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:42 volumio sudo[4388]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:43 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 16 15:20:43 volumio ntfs-3g[4271]: Unmounting /dev/sda1 (musica) Sep 16 15:20:43 volumio systemd[1]: media-musica.mount: Succeeded. Sep 16 15:20:43 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 16 15:20:43 volumio systemd[1]: Started dynamicswap service. Sep 16 15:20:43 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 16 15:20:43 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 16 15:20:43 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Sep 16 15:20:43 volumio systemd[1]: Stopped Volumio Backend Module. Sep 16 15:20:43 volumio systemd[1]: Started Volumio Backend Module. Sep 16 15:20:43 volumio systemd[1]: Started dynamicswap service. Sep 16 15:20:43 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 16 15:20:45 volumio volumio[4406]: info: ------------------------------------------- Sep 16 15:20:45 volumio volumio[4406]: info: ----- Volumio3 ---- Sep 16 15:20:45 volumio volumio[4406]: info: ------------------------------------------- Sep 16 15:20:45 volumio volumio[4406]: info: ----- System startup ---- Sep 16 15:20:45 volumio volumio[4406]: info: ------------------------------------------- Sep 16 15:20:46 volumio volumio[4406]: info: MYVOLUMIO Environment detected Sep 16 15:20:46 volumio volumio[4406]: info: Plugin folders cleanup Sep 16 15:20:46 volumio volumio[4406]: info: Scanning into folder /volumio/app/plugins/ Sep 16 15:20:46 volumio volumio[4406]: info: Scanning category audio_interface Sep 16 15:20:46 volumio volumio[4406]: info: Scanning category miscellanea Sep 16 15:20:46 volumio volumio[4406]: info: Scanning category music_service Sep 16 15:20:46 volumio volumio[4406]: info: Scanning category plugins.json Sep 16 15:20:46 volumio volumio[4406]: info: Scanning category system_controller Sep 16 15:20:46 volumio volumio[4406]: info: Scanning category user_interface Sep 16 15:20:46 volumio volumio[4406]: info: Scanning into folder /data/plugins/ Sep 16 15:20:46 volumio volumio[4406]: info: Scanning category music_service Sep 16 15:20:46 volumio volumio[4406]: info: Plugin folders cleanup completed Sep 16 15:20:46 volumio volumio[4406]: info: ------------------------------------------- Sep 16 15:20:46 volumio volumio[4406]: info: ----- Core plugins startup ---- Sep 16 15:20:46 volumio volumio[4406]: info: ------------------------------------------- Sep 16 15:20:46 volumio volumio[4406]: info: Loading plugins from folder /volumio/app/plugins/ Sep 16 15:20:46 volumio volumio[4406]: info: Adding plugin upnp to MyMusic Plugins Sep 16 15:20:46 volumio volumio[4406]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 16 15:20:46 volumio volumio[4406]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 16 15:20:46 volumio volumio[4406]: info: Loading plugins from folder /data/plugins/ Sep 16 15:20:46 volumio volumio[4406]: info: Loading plugin "system"... Sep 16 15:20:46 volumio volumio[4406]: info: Loading plugin "appearance"... Sep 16 15:20:47 volumio volumio[4406]: info: Loading plugin "network"... Sep 16 15:20:47 volumio volumio[4406]: info: Refreshing Cached IP Addresses Sep 16 15:20:47 volumio sudo[4430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 16 15:20:47 volumio sudo[4430]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:47 volumio sudo[4430]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:47 volumio volumio[4406]: info: Loading plugin "services"... Sep 16 15:20:47 volumio sudo[4432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 16 15:20:47 volumio sudo[4432]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:47 volumio volumio-remote-updater[484]: [2025-09-16 15:20:47] [connect] Successful connection Sep 16 15:20:47 volumio volumio[4406]: info: Loading plugin "alsa_controller"... Sep 16 15:20:47 volumio sudo[4432]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:47 volumio sudo[4435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 16 15:20:47 volumio sudo[4435]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:47 volumio sudo[4435]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:47 volumio volumio[4406]: info: Loading plugin "wizard"... Sep 16 15:20:47 volumio volumio[4406]: info: Loading plugin "volumio_command_line_client"... Sep 16 15:20:47 volumio volumio[4406]: info: Loading plugin "upnp"... Sep 16 15:20:47 volumio volumio[4406]: info: [1758036047416] Starting Upmpd Daemon Sep 16 15:20:47 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:20:47 volumio volumio[4406]: info: Loading plugin "my_music"... Sep 16 15:20:47 volumio volumio[4406]: info: Loading plugin "mpd"... Sep 16 15:20:47 volumio volumio[4406]: info: Creating MPD Configuration file Sep 16 15:20:47 volumio volumio[4406]: info: Loading plugin "upnp_browser"... Sep 16 15:20:47 volumio sudo[4443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 15:20:47 volumio sudo[4443]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:47 volumio sudo[4443]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:47 volumio sudo[4445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 15:20:47 volumio sudo[4445]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:47 volumio systemd[1]: Stopping Music Player Daemon... Sep 16 15:20:47 volumio systemd[1]: mpd.service: Succeeded. Sep 16 15:20:47 volumio systemd[1]: Stopped Music Player Daemon. Sep 16 15:20:47 volumio systemd[1]: Starting Music Player Daemon... Sep 16 15:20:48 volumio volumio[4406]: info: Loading plugin "networkfs"... Sep 16 15:20:48 volumio volumio[4406]: info: Starting Udev Watcher for removable devices Sep 16 15:20:48 volumio volumio[4406]: info: Ignoring mount for partition: boot Sep 16 15:20:48 volumio volumio[4406]: info: Ignoring mount for partition: volumio Sep 16 15:20:48 volumio volumio[4406]: info: Ignoring mount for partition: volumio_data Sep 16 15:20:48 volumio volumio[4406]: info: Mounting Device musica Sep 16 15:20:48 volumio sudo[4456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/musica -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 16 15:20:48 volumio sudo[4456]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:49 volumio mpd[4450]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 15:20:49 volumio mpd[4450]: config: Found database setting without music_directory - disabling database Sep 16 15:20:49 volumio mpd[4450]: output: No 'audio_output' defined in config file Sep 16 15:20:49 volumio mpd[4450]: output: Attempt to detect audio output device Sep 16 15:20:49 volumio mpd[4450]: output: Attempting to detect a alsa audio device Sep 16 15:20:49 volumio mpd[4450]: alsa_output: Error opening default ALSA device: Device or resource busy Sep 16 15:20:49 volumio mpd[4450]: output: Attempting to detect a oss audio device Sep 16 15:20:49 volumio mpd[4450]: oss_output: Error opening OSS device "/dev/dsp": No such file or directory Sep 16 15:20:49 volumio mpd[4450]: oss_output: Error opening OSS device "/dev/sound/dsp": No such file or directory Sep 16 15:20:49 volumio mpd[4450]: output: Attempting to detect a pulse audio device Sep 16 15:20:49 volumio mpd[4450]: output: Attempting to detect a jack audio device Sep 16 15:20:49 volumio mpd[4450]: output: Successfully detected a jack audio device Sep 16 15:20:49 volumio mpd[4450]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 16 15:20:49 volumio mpd[4450]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 16 15:20:49 volumio mpd[4450]: zeroconf: No global port, disabling zeroconf Sep 16 15:20:49 volumio systemd[1]: Started Music Player Daemon. Sep 16 15:20:49 volumio sudo[4445]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:49 volumio ntfs-3g[4463]: Version 2017.3.23AR.3 integrated FUSE 28 Sep 16 15:20:49 volumio ntfs-3g[4463]: Mounted /dev/sda1 (Read-Write, label "musica", NTFS 3.1) Sep 16 15:20:49 volumio ntfs-3g[4463]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 16 15:20:49 volumio ntfs-3g[4463]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Sep 16 15:20:49 volumio ntfs-3g[4463]: Global ownership and permissions enforced, configuration type 7 Sep 16 15:20:49 volumio sudo[4456]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:49 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:20:49 volumio volumio[4406]: info: Loading plugin "alarm-clock"... Sep 16 15:20:49 volumio volumio[4406]: info: Loading plugin "airplay_emulation"... Sep 16 15:20:49 volumio volumio[4406]: info: Starting Shairport Sync Sep 16 15:20:49 volumio volumio[4406]: info: Loading plugin "last_100"... Sep 16 15:20:49 volumio volumio[4406]: info: Loading plugin "webradio"... Sep 16 15:20:50 volumio volumio[4406]: info: Loading plugin "i2s_dacs"... Sep 16 15:20:50 volumio volumio[4406]: info: I2S DAC not set, start Auto-detection Sep 16 15:20:50 volumio volumio[4406]: info: Loading plugin "volumiodiscovery"... Sep 16 15:20:50 volumio volumio[4406]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 15:20:50 volumio volumio[4406]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:20:50 volumio volumio[4406]: *** WARNING *** For more information see Sep 16 15:20:50 volumio node[4406]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 15:20:50 volumio volumio[4406]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 15:20:50 volumio volumio[4406]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:20:50 volumio volumio[4406]: *** WARNING *** For more information see Sep 16 15:20:50 volumio node[4406]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:20:50 volumio node[4406]: *** WARNING *** For more information see Sep 16 15:20:50 volumio node[4406]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 15:20:50 volumio node[4406]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:20:50 volumio node[4406]: *** WARNING *** For more information see Sep 16 15:20:50 volumio volumio[4406]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 16 15:20:50 volumio volumio[4406]: info: Discovery: Started advertising with name: Volumio Sep 16 15:20:50 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:20:50 volumio volumio[4406]: info: Loading plugin "squeezelite_mc"... Sep 16 15:20:51 volumio volumio[4406]: info: Loading plugin "outputs"... Sep 16 15:20:51 volumio volumio[4406]: info: Loading plugin "albumart"... Sep 16 15:20:51 volumio volumio[4406]: info: Plugin example_plugin is not enabled Sep 16 15:20:51 volumio volumio[4406]: info: Loading plugin "inputs"... Sep 16 15:20:51 volumio volumio[4406]: info: Loading plugin "updater_comm"... Sep 16 15:20:51 volumio volumio[4406]: info: Plugin mpdemulation is not enabled Sep 16 15:20:51 volumio volumio[4406]: info: Loading plugin "rest_api"... Sep 16 15:20:51 volumio volumio[4406]: info: Loading plugin "websocket"... Sep 16 15:20:51 volumio volumio[4406]: info: Loading i18n strings for locale it Sep 16 15:20:51 volumio volumio[4406]: Updating browse sources language Sep 16 15:20:51 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:20:51 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:20:51 volumio volumio[4406]: info: CoreCommandRouter::initPlayerControls Sep 16 15:20:51 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:51 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:51 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:51 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:51 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:51 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:51 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:51 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:51 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:20:51 volumio volumio[4406]: Express server listening on port 3000 Sep 16 15:20:51 volumio volumio[4406]: [Metrics] WebUI: 6s 665.23ms Sep 16 15:20:51 volumio volumio[4406]: info: CoreStateMachine::resetVolumioState Sep 16 15:20:51 volumio volumio[4406]: info: CoreStateMachine::getcurrentVolume Sep 16 15:20:51 volumio volumio[4406]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:20:51 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:51 volumio mpd[4450]: client: [0] opened from local Sep 16 15:20:51 volumio volumio[4406]: info: MPD Permissions set Sep 16 15:20:51 volumio volumio[4406]: Forking 3 albumart workers Sep 16 15:20:51 volumio volumio[4406]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 16 15:20:51 volumio volumio[4406]: wlan0 Interface doesn't support scanning : Device or resource busy Sep 16 15:20:51 volumio volumio[4406]: info: Cannot use regular scanning, forcing with ap-force Sep 16 15:20:51 volumio sudo[4482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 16 15:20:51 volumio sudo[4482]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:52 volumio volumio[4406]: Starting albumart workers Sep 16 15:20:52 volumio volumio[4406]: Starting albumart workers Sep 16 15:20:52 volumio volumio[4406]: Starting albumart workers Sep 16 15:20:54 volumio sudo[4482]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:54 volumio volumio[4406]: info: MPD running with PID4450 Sep 16 15:20:54 volumio volumio[4406]: ,establishing connection Sep 16 15:20:54 volumio mpd[4450]: client: [1] opened from local Sep 16 15:20:54 volumio volumio[4406]: error: MPD error: The expression evaluated to a falsy value: Sep 16 15:20:54 volumio volumio[4406]: assert.ok(self.idling) Sep 16 15:20:54 volumio volumio[4406]: error: MPD error: The expression evaluated to a falsy value: Sep 16 15:20:54 volumio volumio[4406]: assert.ok(self.idling) Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:54 volumio mpd[4450]: exception: No database Sep 16 15:20:54 volumio volumio[4406]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:20:54 volumio volumio[4406]: info: CoreStateMachine::pushState Sep 16 15:20:54 volumio volumio[4406]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:54 volumio volumio[4406]: info: CoreStateMachine::updateTrackBlock Sep 16 15:20:54 volumio volumio[4406]: info: CorePlayQueue::getTrackBlock Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:20:54 volumio volumio[4406]: info: CoreStateMachine::setRepeat false single undefined Sep 16 15:20:54 volumio volumio[4406]: info: CoreStateMachine::pushState Sep 16 15:20:54 volumio volumio[4406]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:54 volumio volumio[4406]: info: CoreStateMachine::setRandom false Sep 16 15:20:54 volumio volumio[4406]: info: CoreStateMachine::pushState Sep 16 15:20:54 volumio volumio[4406]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:54 volumio volumio[4406]: info: Setting Device type: Raspberry PI Sep 16 15:20:54 volumio volumio[4406]: error: updateQueue error: null Sep 16 15:20:54 volumio volumio[4406]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Sep 16 15:20:54 volumio volumio[4406]: info: Completed loading Core Plugins Sep 16 15:20:54 volumio volumio[4406]: info: Preparing to generate the ALSA configuration file Sep 16 15:20:54 volumio volumio[4406]: info: Asound.conf file unchanged, so no further update is needed Sep 16 15:20:54 volumio volumio[4406]: info: Output device has changed, restarting MPD Sep 16 15:20:54 volumio sudo[4541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 15:20:54 volumio volumio[4406]: info: Output device has changed, restarting Shairport Sync Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:54 volumio sudo[4541]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:54 volumio sudo[4541]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:54 volumio sudo[4543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 15:20:54 volumio sudo[4543]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:54 volumio systemd[1]: Stopping Music Player Daemon... Sep 16 15:20:54 volumio volumio[4406]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:20:54 volumio systemd[1]: mpd.service: Succeeded. Sep 16 15:20:54 volumio volumio[4406]: info: ___________ START PLUGINS ___________ Sep 16 15:20:54 volumio systemd[1]: Stopped Music Player Daemon. Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:20:54 volumio volumio[4406]: info: [1758036054555] CoreMusicLibrary::Adding element Media Servers Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:20:54 volumio volumio[4406]: Cannot find translation for sourceMedia Servers Sep 16 15:20:54 volumio systemd[1]: Starting Music Player Daemon... Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:54 volumio volumio[4406]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:54 volumio volumio[4406]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:20:54 volumio volumio[4406]: info: [1758036054700] CoreMusicLibrary::Adding element Last_100 Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:20:54 volumio volumio[4406]: Cannot find translation for sourceMedia Servers Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:20:54 volumio volumio[4406]: info: [1758036054708] CoreMusicLibrary::Adding element Webradio Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:20:54 volumio volumio[4406]: Cannot find translation for sourceMedia Servers Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 15:20:54 volumio volumio[4406]: info: [squeezelite_mc] Starting proxy server... Sep 16 15:20:54 volumio volumio[4406]: info: Volumio Calling Home Sep 16 15:20:54 volumio volumio[4406]: info: [squeezelite_mc] Proxy server started on port 46245 Sep 16 15:20:54 volumio volumio[4406]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:20:54 volumio volumio[4406]: Unhandled rejection Error: No sockets available, cannot start. Sep 16 15:20:54 volumio volumio[4406]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Sep 16 15:20:54 volumio volumio[4406]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Sep 16 15:20:54 volumio volumio[4406]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Sep 16 15:20:54 volumio volumio[4406]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Sep 16 15:20:54 volumio volumio[4406]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Sep 16 15:20:54 volumio volumio[4406]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Sep 16 15:20:54 volumio volumio[4406]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Sep 16 15:20:54 volumio volumio[4406]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Sep 16 15:20:54 volumio volumio[4406]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Sep 16 15:20:54 volumio volumio[4406]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Sep 16 15:20:54 volumio volumio[4406]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Sep 16 15:20:54 volumio volumio[4406]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 16 15:20:54 volumio volumio[4406]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 16 15:20:54 volumio volumio[4406]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Sep 16 15:20:54 volumio volumio[4406]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Sep 16 15:20:54 volumio volumio[4406]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 16 15:20:55 volumio volumio[4406]: info: MPD Permissions set Sep 16 15:20:55 volumio volumio[4406]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:20:55 volumio volumio[4406]: info: CoreStateMachine::pushState Sep 16 15:20:55 volumio volumio[4406]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:55 volumio volumio[4406]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:55 volumio volumio[4406]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Sep 16 15:20:55 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:55 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:55 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:55 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:55 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:55 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:55 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:55 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:55 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:20:55 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:55 volumio volumio[4406]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:20:55 volumio volumio[4406]: info: CoreStateMachine::pushState Sep 16 15:20:55 volumio volumio[4406]: info: CorePlayQueue::getTrack 0 Sep 16 15:20:55 volumio volumio[4406]: info: CoreCommandRouter::volumioPushState Sep 16 15:20:55 volumio volumio[4406]: info: [squeezelite_mc] Server discovery started Sep 16 15:20:55 volumio volumio[4406]: info: [squeezelite_mc] Player finder started Sep 16 15:20:55 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:55 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:55 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:20:55 volumio volumio[4406]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:20:55 volumio volumio[4406]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Sep 16 15:20:55 volumio volumio[4406]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 15:20:55 volumio volumio[4406]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 16 15:20:55 volumio volumio[4406]: at doSend (dgram.js:692:16) Sep 16 15:20:55 volumio volumio[4406]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Sep 16 15:20:55 volumio volumio[4406]: at afterDns (dgram.js:638:5) Sep 16 15:20:55 volumio volumio[4406]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 16 15:20:55 volumio volumio[4406]: errno: -101, Sep 16 15:20:55 volumio volumio[4406]: code: 'ENETUNREACH', Sep 16 15:20:55 volumio volumio[4406]: syscall: 'send', Sep 16 15:20:55 volumio volumio[4406]: address: '255.255.255.255', Sep 16 15:20:55 volumio volumio[4406]: port: 3483 Sep 16 15:20:55 volumio volumio[4406]: } Sep 16 15:20:55 volumio volumio[4406]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 15:20:55 volumio sudo[4578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-16 15:19 Sep 16 15:20:55 volumio sudo[4578]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:20:56 volumio sudo[4578]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:56 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 16 15:20:56 volumio ntfs-3g[4463]: Unmounting /dev/sda1 (musica) Sep 16 15:20:56 volumio sudo[4543]: pam_unix(sudo:session): session closed for user root Sep 16 15:20:56 volumio systemd[1]: media-musica.mount: Succeeded. Sep 16 15:20:56 volumio mpd[4560]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 15:20:56 volumio mpd[4560]: config: Found database setting without music_directory - disabling database Sep 16 15:20:56 volumio mpd[4560]: output: No 'audio_output' defined in config file Sep 16 15:20:56 volumio mpd[4560]: output: Attempt to detect audio output device Sep 16 15:20:56 volumio mpd[4560]: output: Attempting to detect a alsa audio device Sep 16 15:20:56 volumio mpd[4560]: alsa_output: Error opening default ALSA device: Device or resource busy Sep 16 15:20:56 volumio mpd[4560]: output: Attempting to detect a oss audio device Sep 16 15:20:56 volumio mpd[4560]: oss_output: Error opening OSS device "/dev/dsp": No such file or directory Sep 16 15:20:56 volumio mpd[4560]: oss_output: Error opening OSS device "/dev/sound/dsp": No such file or directory Sep 16 15:20:56 volumio mpd[4560]: output: Attempting to detect a pulse audio device Sep 16 15:20:56 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 16 15:20:56 volumio mpd[4560]: output: Attempting to detect a jack audio device Sep 16 15:20:56 volumio mpd[4560]: output: Successfully detected a jack audio device Sep 16 15:20:56 volumio mpd[4560]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 16 15:20:56 volumio mpd[4560]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 16 15:20:56 volumio systemd[1]: Started dynamicswap service. Sep 16 15:20:56 volumio mpd[4560]: zeroconf: No global port, disabling zeroconf Sep 16 15:20:56 volumio systemd[1]: Started Music Player Daemon. Sep 16 15:20:56 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 16 15:20:56 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 16 15:20:56 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Sep 16 15:20:56 volumio systemd[1]: Stopped Volumio Backend Module. Sep 16 15:20:56 volumio systemd[1]: Started Volumio Backend Module. Sep 16 15:20:56 volumio systemd[1]: Started dynamicswap service. Sep 16 15:20:56 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 16 15:20:58 volumio volumio[4596]: info: ------------------------------------------- Sep 16 15:20:58 volumio volumio[4596]: info: ----- Volumio3 ---- Sep 16 15:20:58 volumio volumio[4596]: info: ------------------------------------------- Sep 16 15:20:58 volumio volumio[4596]: info: ----- System startup ---- Sep 16 15:20:58 volumio volumio[4596]: info: ------------------------------------------- Sep 16 15:20:59 volumio volumio[4596]: info: MYVOLUMIO Environment detected Sep 16 15:20:59 volumio volumio[4596]: info: Plugin folders cleanup Sep 16 15:20:59 volumio volumio[4596]: info: Scanning into folder /volumio/app/plugins/ Sep 16 15:20:59 volumio volumio[4596]: info: Scanning category audio_interface Sep 16 15:20:59 volumio volumio[4596]: info: Scanning category miscellanea Sep 16 15:20:59 volumio volumio[4596]: info: Scanning category music_service Sep 16 15:20:59 volumio volumio[4596]: info: Scanning category plugins.json Sep 16 15:20:59 volumio volumio[4596]: info: Scanning category system_controller Sep 16 15:20:59 volumio volumio[4596]: info: Scanning category user_interface Sep 16 15:20:59 volumio volumio[4596]: info: Scanning into folder /data/plugins/ Sep 16 15:20:59 volumio volumio[4596]: info: Scanning category music_service Sep 16 15:20:59 volumio volumio[4596]: info: Plugin folders cleanup completed Sep 16 15:20:59 volumio volumio[4596]: info: ------------------------------------------- Sep 16 15:20:59 volumio volumio[4596]: info: ----- Core plugins startup ---- Sep 16 15:20:59 volumio volumio[4596]: info: ------------------------------------------- Sep 16 15:20:59 volumio volumio[4596]: info: Loading plugins from folder /volumio/app/plugins/ Sep 16 15:20:59 volumio volumio[4596]: info: Adding plugin upnp to MyMusic Plugins Sep 16 15:20:59 volumio volumio[4596]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 16 15:20:59 volumio volumio[4596]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 16 15:20:59 volumio volumio[4596]: info: Loading plugins from folder /data/plugins/ Sep 16 15:20:59 volumio volumio[4596]: info: Loading plugin "system"... Sep 16 15:20:59 volumio volumio[4596]: info: Loading plugin "appearance"... Sep 16 15:21:00 volumio nmbd[626]: [2025/09/16 15:21:00.213684, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) Sep 16 15:21:00 volumio nmbd[626]: reload_interfaces: No subnets to listen to. Waiting.. Sep 16 15:21:00 volumio volumio[4596]: info: Loading plugin "network"... Sep 16 15:21:00 volumio volumio[4596]: info: Refreshing Cached IP Addresses Sep 16 15:21:00 volumio sudo[4621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 16 15:21:00 volumio sudo[4621]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:00 volumio sudo[4621]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:00 volumio volumio[4596]: info: Loading plugin "services"... Sep 16 15:21:00 volumio volumio[4596]: info: Loading plugin "alsa_controller"... Sep 16 15:21:00 volumio sudo[4623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 16 15:21:00 volumio sudo[4623]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:00 volumio sudo[4623]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:00 volumio sudo[4626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 16 15:21:00 volumio sudo[4626]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:00 volumio volumio[4596]: info: Loading plugin "wizard"... Sep 16 15:21:00 volumio volumio[4596]: info: Loading plugin "volumio_command_line_client"... Sep 16 15:21:00 volumio volumio[4596]: info: Loading plugin "upnp"... Sep 16 15:21:00 volumio volumio[4596]: info: [1758036060470] Starting Upmpd Daemon Sep 16 15:21:00 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:21:00 volumio volumio[4596]: info: Loading plugin "my_music"... Sep 16 15:21:00 volumio volumio[4596]: info: Loading plugin "mpd"... Sep 16 15:21:00 volumio volumio[4596]: info: Creating MPD Configuration file Sep 16 15:21:00 volumio volumio[4596]: info: Loading plugin "upnp_browser"... Sep 16 15:21:00 volumio sudo[4634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 15:21:00 volumio sudo[4634]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:00 volumio sudo[4634]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:00 volumio sudo[4636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 15:21:00 volumio sudo[4636]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:00 volumio systemd[1]: Stopping Music Player Daemon... Sep 16 15:21:00 volumio systemd[1]: mpd.service: Succeeded. Sep 16 15:21:00 volumio systemd[1]: Stopped Music Player Daemon. Sep 16 15:21:00 volumio systemd[1]: Starting Music Player Daemon... Sep 16 15:21:01 volumio volumio[4596]: info: Loading plugin "networkfs"... Sep 16 15:21:01 volumio volumio[4596]: info: Starting Udev Watcher for removable devices Sep 16 15:21:01 volumio volumio[4596]: info: Ignoring mount for partition: boot Sep 16 15:21:01 volumio volumio[4596]: info: Ignoring mount for partition: volumio Sep 16 15:21:01 volumio volumio[4596]: info: Ignoring mount for partition: volumio_data Sep 16 15:21:01 volumio volumio[4596]: info: Mounting Device musica Sep 16 15:21:01 volumio sudo[4648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/musica -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 16 15:21:01 volumio sudo[4648]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:02 volumio volumio-remote-updater[484]: [2025-09-16 15:21:02] [connect] Successful connection Sep 16 15:21:02 volumio mpd[4642]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 15:21:02 volumio mpd[4642]: config: Found database setting without music_directory - disabling database Sep 16 15:21:02 volumio mpd[4642]: output: No 'audio_output' defined in config file Sep 16 15:21:02 volumio mpd[4642]: output: Attempt to detect audio output device Sep 16 15:21:02 volumio mpd[4642]: output: Attempting to detect a alsa audio device Sep 16 15:21:02 volumio mpd[4642]: alsa_output: Error opening default ALSA device: Device or resource busy Sep 16 15:21:02 volumio mpd[4642]: output: Attempting to detect a oss audio device Sep 16 15:21:02 volumio mpd[4642]: oss_output: Error opening OSS device "/dev/dsp": No such file or directory Sep 16 15:21:02 volumio mpd[4642]: oss_output: Error opening OSS device "/dev/sound/dsp": No such file or directory Sep 16 15:21:02 volumio mpd[4642]: output: Attempting to detect a pulse audio device Sep 16 15:21:02 volumio mpd[4642]: output: Attempting to detect a jack audio device Sep 16 15:21:02 volumio mpd[4642]: output: Successfully detected a jack audio device Sep 16 15:21:02 volumio mpd[4642]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 16 15:21:02 volumio mpd[4642]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 16 15:21:02 volumio mpd[4642]: zeroconf: No global port, disabling zeroconf Sep 16 15:21:02 volumio systemd[1]: Started Music Player Daemon. Sep 16 15:21:02 volumio sudo[4636]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:02 volumio ntfs-3g[4655]: Version 2017.3.23AR.3 integrated FUSE 28 Sep 16 15:21:02 volumio ntfs-3g[4655]: Mounted /dev/sda1 (Read-Write, label "musica", NTFS 3.1) Sep 16 15:21:02 volumio ntfs-3g[4655]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 16 15:21:02 volumio ntfs-3g[4655]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Sep 16 15:21:02 volumio ntfs-3g[4655]: Global ownership and permissions enforced, configuration type 7 Sep 16 15:21:02 volumio sudo[4648]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:02 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:21:02 volumio volumio[4596]: info: Loading plugin "alarm-clock"... Sep 16 15:21:02 volumio sudo[4626]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:02 volumio volumio[4596]: info: Loading plugin "airplay_emulation"... Sep 16 15:21:02 volumio volumio[4596]: info: Starting Shairport Sync Sep 16 15:21:02 volumio volumio[4596]: info: Loading plugin "last_100"... Sep 16 15:21:02 volumio volumio[4596]: info: Loading plugin "webradio"... Sep 16 15:21:03 volumio volumio[4596]: info: Loading plugin "i2s_dacs"... Sep 16 15:21:03 volumio volumio[4596]: info: I2S DAC not set, start Auto-detection Sep 16 15:21:03 volumio volumio[4596]: info: Loading plugin "volumiodiscovery"... Sep 16 15:21:03 volumio volumio[4596]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 15:21:03 volumio volumio[4596]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:21:03 volumio volumio[4596]: *** WARNING *** For more information see Sep 16 15:21:03 volumio node[4596]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 15:21:03 volumio volumio[4596]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 15:21:03 volumio volumio[4596]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:21:03 volumio volumio[4596]: *** WARNING *** For more information see Sep 16 15:21:03 volumio node[4596]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:21:03 volumio node[4596]: *** WARNING *** For more information see Sep 16 15:21:03 volumio node[4596]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 15:21:03 volumio node[4596]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:21:03 volumio node[4596]: *** WARNING *** For more information see Sep 16 15:21:03 volumio volumio[4596]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 16 15:21:03 volumio volumio[4596]: info: Discovery: Started advertising with name: Volumio Sep 16 15:21:03 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:21:03 volumio volumio[4596]: info: Loading plugin "squeezelite_mc"... Sep 16 15:21:04 volumio volumio[4596]: info: Loading plugin "outputs"... Sep 16 15:21:04 volumio volumio[4596]: info: Loading plugin "albumart"... Sep 16 15:21:04 volumio volumio[4596]: info: Plugin example_plugin is not enabled Sep 16 15:21:04 volumio volumio[4596]: info: Loading plugin "inputs"... Sep 16 15:21:04 volumio volumio[4596]: info: Loading plugin "updater_comm"... Sep 16 15:21:04 volumio volumio[4596]: info: Plugin mpdemulation is not enabled Sep 16 15:21:04 volumio volumio[4596]: info: Loading plugin "rest_api"... Sep 16 15:21:04 volumio volumio[4596]: info: Loading plugin "websocket"... Sep 16 15:21:04 volumio volumio[4596]: info: Loading i18n strings for locale it Sep 16 15:21:04 volumio volumio[4596]: Updating browse sources language Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::initPlayerControls Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:21:04 volumio volumio[4596]: Express server listening on port 3000 Sep 16 15:21:04 volumio volumio[4596]: [Metrics] WebUI: 6s 641.83ms Sep 16 15:21:04 volumio volumio[4596]: info: CoreStateMachine::resetVolumioState Sep 16 15:21:04 volumio volumio[4596]: info: CoreStateMachine::getcurrentVolume Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:21:04 volumio mpd[4642]: client: [0] opened from local Sep 16 15:21:04 volumio volumio[4596]: info: MPD Permissions set Sep 16 15:21:04 volumio volumio-remote-updater[484]: [2025-09-16 15:21:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1758036062 101 Sep 16 15:21:04 volumio volumio[4596]: Forking 3 albumart workers Sep 16 15:21:04 volumio volumio[4596]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Sep 16 15:21:04 volumio mpd[4642]: exception: No database Sep 16 15:21:04 volumio mpd[4642]: exception: No database Sep 16 15:21:04 volumio volumio[4596]: info: MPD running with PID4642 Sep 16 15:21:04 volumio volumio[4596]: ,establishing connection Sep 16 15:21:04 volumio mpd[4642]: client: [1] opened from local Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:04 volumio volumio[4596]: error: updateQueue error: null Sep 16 15:21:04 volumio volumio[4596]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Sep 16 15:21:04 volumio volumio[4596]: info: CoreStateMachine::setRepeat false single undefined Sep 16 15:21:04 volumio volumio[4596]: info: CoreStateMachine::pushState Sep 16 15:21:04 volumio volumio[4596]: info: CorePlayQueue::getTrack 0 Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::volumioPushState Sep 16 15:21:04 volumio volumio[4596]: info: CoreStateMachine::setRandom false Sep 16 15:21:04 volumio volumio[4596]: info: CoreStateMachine::pushState Sep 16 15:21:04 volumio volumio[4596]: info: CorePlayQueue::getTrack 0 Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::volumioPushState Sep 16 15:21:04 volumio volumio[4596]: info: Setting Device type: Raspberry PI Sep 16 15:21:04 volumio volumio[4596]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:21:04 volumio volumio[4596]: info: CoreStateMachine::pushState Sep 16 15:21:04 volumio volumio[4596]: info: CorePlayQueue::getTrack 0 Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::volumioPushState Sep 16 15:21:04 volumio volumio[4596]: info: CoreStateMachine::updateTrackBlock Sep 16 15:21:04 volumio volumio[4596]: info: CorePlayQueue::getTrackBlock Sep 16 15:21:04 volumio volumio[4596]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:21:04 volumio volumio[4596]: error: updateQueue error: null Sep 16 15:21:04 volumio volumio[4596]: info: Completed loading Core Plugins Sep 16 15:21:04 volumio volumio[4596]: info: Preparing to generate the ALSA configuration file Sep 16 15:21:05 volumio volumio[4596]: info: Asound.conf file unchanged, so no further update is needed Sep 16 15:21:05 volumio volumio[4596]: info: Output device has changed, restarting MPD Sep 16 15:21:05 volumio volumio[4596]: info: Output device has changed, restarting Shairport Sync Sep 16 15:21:05 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:05 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:21:05 volumio sudo[4706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 15:21:05 volumio sudo[4706]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:05 volumio sudo[4706]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:05 volumio sudo[4707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 15:21:05 volumio sudo[4707]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:05 volumio volumio[4596]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:21:05 volumio volumio[4596]: info: ___________ START PLUGINS ___________ Sep 16 15:21:05 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:21:05 volumio volumio[4596]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:21:05 volumio volumio[4596]: info: [1758036065271] CoreMusicLibrary::Adding element Media Servers Sep 16 15:21:05 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:21:05 volumio volumio[4596]: Cannot find translation for sourceMedia Servers Sep 16 15:21:05 volumio systemd[1]: Stopping Music Player Daemon... Sep 16 15:21:05 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:05 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:21:05 volumio systemd[1]: mpd.service: Succeeded. Sep 16 15:21:05 volumio systemd[1]: Stopped Music Player Daemon. Sep 16 15:21:05 volumio systemd[1]: Starting Music Player Daemon... Sep 16 15:21:05 volumio volumio[4596]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:21:05 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:05 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:21:05 volumio volumio[4596]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:21:05 volumio volumio[4596]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:21:05 volumio volumio[4596]: info: [1758036065600] CoreMusicLibrary::Adding element Last_100 Sep 16 15:21:05 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:21:05 volumio volumio[4596]: Cannot find translation for sourceMedia Servers Sep 16 15:21:05 volumio volumio[4596]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:21:05 volumio volumio[4596]: info: [1758036065605] CoreMusicLibrary::Adding element Webradio Sep 16 15:21:05 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:21:05 volumio volumio[4596]: Cannot find translation for sourceMedia Servers Sep 16 15:21:05 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 15:21:05 volumio volumio[4596]: info: [squeezelite_mc] Starting proxy server... Sep 16 15:21:05 volumio volumio[4596]: info: Volumio Calling Home Sep 16 15:21:05 volumio volumio[4596]: info: [squeezelite_mc] Proxy server started on port 36667 Sep 16 15:21:06 volumio volumio[4596]: Starting albumart workers Sep 16 15:21:06 volumio volumio[4596]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:21:06 volumio volumio[4596]: Unhandled rejection Error: No sockets available, cannot start. Sep 16 15:21:06 volumio volumio[4596]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Sep 16 15:21:06 volumio volumio[4596]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Sep 16 15:21:06 volumio volumio[4596]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Sep 16 15:21:06 volumio volumio[4596]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Sep 16 15:21:06 volumio volumio[4596]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Sep 16 15:21:06 volumio volumio[4596]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Sep 16 15:21:06 volumio volumio[4596]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Sep 16 15:21:06 volumio volumio[4596]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Sep 16 15:21:06 volumio volumio[4596]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Sep 16 15:21:06 volumio volumio[4596]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Sep 16 15:21:06 volumio volumio[4596]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Sep 16 15:21:06 volumio volumio[4596]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 16 15:21:06 volumio volumio[4596]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 16 15:21:06 volumio volumio[4596]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Sep 16 15:21:06 volumio volumio[4596]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Sep 16 15:21:06 volumio volumio[4596]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 16 15:21:06 volumio volumio[4596]: Starting albumart workers Sep 16 15:21:06 volumio volumio[4596]: info: MPD Permissions set Sep 16 15:21:06 volumio volumio[4596]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:21:06 volumio volumio[4596]: info: CoreStateMachine::pushState Sep 16 15:21:06 volumio volumio[4596]: info: CorePlayQueue::getTrack 0 Sep 16 15:21:06 volumio volumio[4596]: info: CoreCommandRouter::volumioPushState Sep 16 15:21:06 volumio volumio[4596]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Sep 16 15:21:06 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:06 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:06 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:06 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:06 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:06 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:06 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:06 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:06 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:21:06 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:06 volumio volumio[4596]: Starting albumart workers Sep 16 15:21:06 volumio volumio[4596]: info: Starting Shairport Sync Sep 16 15:21:06 volumio volumio[4596]: info: Starting Shairport Sync Sep 16 15:21:06 volumio volumio[4596]: info: Starting Shairport Sync Sep 16 15:21:06 volumio sudo[4732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 16 15:21:06 volumio sudo[4732]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 16 15:21:06 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 16 15:21:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 16 15:21:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 16 15:21:06 volumio sudo[4732]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:06 volumio sudo[4738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 16 15:21:06 volumio sudo[4738]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:06 volumio sudo[4736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 16 15:21:06 volumio sudo[4736]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:07 volumio volumio[4596]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:21:07 volumio volumio[4596]: info: CoreStateMachine::pushState Sep 16 15:21:07 volumio volumio[4596]: info: CorePlayQueue::getTrack 0 Sep 16 15:21:07 volumio volumio[4596]: info: CoreCommandRouter::volumioPushState Sep 16 15:21:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 16 15:21:07 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 16 15:21:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 16 15:21:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 16 15:21:07 volumio sudo[4738]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:07 volumio volumio[4596]: info: [squeezelite_mc] Server discovery started Sep 16 15:21:07 volumio volumio[4596]: info: [squeezelite_mc] Player finder started Sep 16 15:21:07 volumio sudo[4736]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:07 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:07 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:07 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:07 volumio volumio[4596]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:21:07 volumio volumio[4596]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Sep 16 15:21:07 volumio volumio[4596]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 15:21:07 volumio mpd[4723]: Sep 16 15:21 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 15:21:07 volumio volumio[4596]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 16 15:21:07 volumio volumio[4596]: at doSend (dgram.js:692:16) Sep 16 15:21:07 volumio volumio[4596]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Sep 16 15:21:07 volumio volumio[4596]: at afterDns (dgram.js:638:5) Sep 16 15:21:07 volumio volumio[4596]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 16 15:21:07 volumio volumio[4596]: errno: -101, Sep 16 15:21:07 volumio volumio[4596]: code: 'ENETUNREACH', Sep 16 15:21:07 volumio volumio[4596]: syscall: 'send', Sep 16 15:21:07 volumio volumio[4596]: address: '255.255.255.255', Sep 16 15:21:07 volumio volumio[4596]: port: 3483 Sep 16 15:21:07 volumio volumio[4596]: } Sep 16 15:21:07 volumio volumio[4596]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 15:21:07 volumio mpd[4723]: Sep 16 15:21 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 16 15:21:07 volumio mpd[4723]: Sep 16 15:21 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 16 15:21:07 volumio systemd[1]: Started Music Player Daemon. Sep 16 15:21:07 volumio sudo[4707]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:08 volumio sudo[4783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-16 15:20 Sep 16 15:21:08 volumio sudo[4783]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:08 volumio sudo[4783]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:08 volumio volumio-remote-updater[484]: [2025-09-16 15:21:08] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 16 15:21:08 volumio volumio-remote-updater[484]: [2025-09-16 15:21:08] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 16 15:21:08 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 16 15:21:08 volumio ntfs-3g[4655]: Unmounting /dev/sda1 (musica) Sep 16 15:21:08 volumio systemd[1]: media-musica.mount: Succeeded. Sep 16 15:21:08 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 16 15:21:08 volumio systemd[1]: Started dynamicswap service. Sep 16 15:21:08 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 16 15:21:08 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 16 15:21:08 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. Sep 16 15:21:08 volumio systemd[1]: Stopped Volumio Backend Module. Sep 16 15:21:08 volumio systemd[1]: Started Volumio Backend Module. Sep 16 15:21:08 volumio systemd[1]: Started dynamicswap service. Sep 16 15:21:08 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 16 15:21:10 volumio volumio[4798]: info: ------------------------------------------- Sep 16 15:21:10 volumio volumio[4798]: info: ----- Volumio3 ---- Sep 16 15:21:10 volumio volumio[4798]: info: ------------------------------------------- Sep 16 15:21:10 volumio volumio[4798]: info: ----- System startup ---- Sep 16 15:21:10 volumio volumio[4798]: info: ------------------------------------------- Sep 16 15:21:11 volumio volumio[4798]: info: MYVOLUMIO Environment detected Sep 16 15:21:11 volumio volumio[4798]: info: Plugin folders cleanup Sep 16 15:21:11 volumio volumio[4798]: info: Scanning into folder /volumio/app/plugins/ Sep 16 15:21:11 volumio volumio[4798]: info: Scanning category audio_interface Sep 16 15:21:11 volumio volumio[4798]: info: Scanning category miscellanea Sep 16 15:21:11 volumio volumio[4798]: info: Scanning category music_service Sep 16 15:21:11 volumio volumio[4798]: info: Scanning category plugins.json Sep 16 15:21:11 volumio volumio[4798]: info: Scanning category system_controller Sep 16 15:21:11 volumio volumio[4798]: info: Scanning category user_interface Sep 16 15:21:11 volumio volumio[4798]: info: Scanning into folder /data/plugins/ Sep 16 15:21:11 volumio volumio[4798]: info: Scanning category music_service Sep 16 15:21:11 volumio volumio[4798]: info: Plugin folders cleanup completed Sep 16 15:21:11 volumio volumio[4798]: info: ------------------------------------------- Sep 16 15:21:11 volumio volumio[4798]: info: ----- Core plugins startup ---- Sep 16 15:21:11 volumio volumio[4798]: info: ------------------------------------------- Sep 16 15:21:11 volumio volumio[4798]: info: Loading plugins from folder /volumio/app/plugins/ Sep 16 15:21:11 volumio volumio[4798]: info: Adding plugin upnp to MyMusic Plugins Sep 16 15:21:11 volumio volumio[4798]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 16 15:21:11 volumio volumio[4798]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 16 15:21:11 volumio volumio[4798]: info: Loading plugins from folder /data/plugins/ Sep 16 15:21:11 volumio volumio[4798]: info: Loading plugin "system"... Sep 16 15:21:11 volumio volumio[4798]: info: Loading plugin "appearance"... Sep 16 15:21:12 volumio volumio[4798]: info: Loading plugin "network"... Sep 16 15:21:12 volumio volumio[4798]: info: Refreshing Cached IP Addresses Sep 16 15:21:12 volumio sudo[4822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 16 15:21:12 volumio sudo[4822]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:12 volumio sudo[4822]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:12 volumio sudo[4824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 16 15:21:12 volumio volumio[4798]: info: Loading plugin "services"... Sep 16 15:21:12 volumio sudo[4824]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:12 volumio volumio[4798]: info: Loading plugin "alsa_controller"... Sep 16 15:21:12 volumio sudo[4824]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:12 volumio sudo[4828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 16 15:21:12 volumio sudo[4828]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:12 volumio volumio[4798]: info: Loading plugin "wizard"... Sep 16 15:21:12 volumio volumio[4798]: info: Loading plugin "volumio_command_line_client"... Sep 16 15:21:12 volumio volumio[4798]: info: Loading plugin "upnp"... Sep 16 15:21:12 volumio volumio[4798]: info: [1758036072688] Starting Upmpd Daemon Sep 16 15:21:12 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:21:12 volumio volumio[4798]: info: Loading plugin "my_music"... Sep 16 15:21:12 volumio volumio[4798]: info: Loading plugin "mpd"... Sep 16 15:21:13 volumio volumio[4798]: info: Creating MPD Configuration file Sep 16 15:21:13 volumio volumio[4798]: info: Loading plugin "upnp_browser"... Sep 16 15:21:13 volumio sudo[4835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 15:21:13 volumio sudo[4835]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:13 volumio sudo[4835]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:13 volumio sudo[4837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 15:21:13 volumio sudo[4837]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:13 volumio systemd[1]: Stopping Music Player Daemon... Sep 16 15:21:13 volumio systemd[1]: mpd.service: Succeeded. Sep 16 15:21:13 volumio systemd[1]: Stopped Music Player Daemon. Sep 16 15:21:13 volumio systemd[1]: Starting Music Player Daemon... Sep 16 15:21:13 volumio volumio-remote-updater[484]: [2025-09-16 15:21:13] [connect] Successful connection Sep 16 15:21:13 volumio volumio[4798]: info: Loading plugin "networkfs"... Sep 16 15:21:13 volumio volumio[4798]: info: Starting Udev Watcher for removable devices Sep 16 15:21:14 volumio volumio[4798]: info: Ignoring mount for partition: boot Sep 16 15:21:14 volumio volumio[4798]: info: Ignoring mount for partition: volumio Sep 16 15:21:14 volumio volumio[4798]: info: Ignoring mount for partition: volumio_data Sep 16 15:21:14 volumio volumio[4798]: info: Mounting Device musica Sep 16 15:21:14 volumio sudo[4848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/musica -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 16 15:21:14 volumio sudo[4848]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:14 volumio mpd[4842]: Sep 16 15:21 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 15:21:14 volumio ntfs-3g[4851]: Version 2017.3.23AR.3 integrated FUSE 28 Sep 16 15:21:14 volumio ntfs-3g[4851]: Mounted /dev/sda1 (Read-Write, label "musica", NTFS 3.1) Sep 16 15:21:14 volumio ntfs-3g[4851]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 16 15:21:14 volumio ntfs-3g[4851]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Sep 16 15:21:14 volumio ntfs-3g[4851]: Global ownership and permissions enforced, configuration type 7 Sep 16 15:21:14 volumio sudo[4848]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:14 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:21:14 volumio volumio[4798]: info: Loading plugin "alarm-clock"... Sep 16 15:21:14 volumio mpd[4842]: Sep 16 15:21 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 16 15:21:14 volumio mpd[4842]: Sep 16 15:21 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 16 15:21:14 volumio systemd[1]: Started Music Player Daemon. Sep 16 15:21:14 volumio sudo[4837]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:15 volumio sudo[4828]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:15 volumio volumio[4798]: info: Loading plugin "airplay_emulation"... Sep 16 15:21:15 volumio volumio[4798]: info: Starting Shairport Sync Sep 16 15:21:15 volumio volumio[4798]: info: Loading plugin "last_100"... Sep 16 15:21:15 volumio volumio[4798]: info: Loading plugin "webradio"... Sep 16 15:21:15 volumio volumio[4798]: info: Loading plugin "i2s_dacs"... Sep 16 15:21:15 volumio volumio[4798]: info: I2S DAC not set, start Auto-detection Sep 16 15:21:15 volumio volumio[4798]: info: Loading plugin "volumiodiscovery"... Sep 16 15:21:15 volumio volumio[4798]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 15:21:15 volumio volumio[4798]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:21:15 volumio volumio[4798]: *** WARNING *** For more information see Sep 16 15:21:15 volumio node[4798]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 15:21:15 volumio volumio[4798]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 15:21:15 volumio volumio[4798]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:21:15 volumio volumio[4798]: *** WARNING *** For more information see Sep 16 15:21:15 volumio node[4798]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:21:15 volumio node[4798]: *** WARNING *** For more information see Sep 16 15:21:15 volumio node[4798]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 15:21:15 volumio node[4798]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:21:15 volumio node[4798]: *** WARNING *** For more information see Sep 16 15:21:15 volumio volumio[4798]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 16 15:21:15 volumio volumio[4798]: info: Discovery: Started advertising with name: Volumio Sep 16 15:21:15 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:21:15 volumio volumio[4798]: info: Loading plugin "squeezelite_mc"... Sep 16 15:21:16 volumio volumio[4798]: info: Loading plugin "outputs"... Sep 16 15:21:16 volumio volumio[4798]: info: Loading plugin "albumart"... Sep 16 15:21:16 volumio volumio[4798]: info: Plugin example_plugin is not enabled Sep 16 15:21:16 volumio volumio[4798]: info: Loading plugin "inputs"... Sep 16 15:21:16 volumio volumio[4798]: info: Loading plugin "updater_comm"... Sep 16 15:21:16 volumio volumio[4798]: info: Plugin mpdemulation is not enabled Sep 16 15:21:16 volumio volumio[4798]: info: Loading plugin "rest_api"... Sep 16 15:21:16 volumio volumio[4798]: info: Loading plugin "websocket"... Sep 16 15:21:16 volumio volumio[4798]: info: Loading i18n strings for locale it Sep 16 15:21:16 volumio volumio[4798]: Updating browse sources language Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::initPlayerControls Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:21:16 volumio volumio[4798]: Express server listening on port 3000 Sep 16 15:21:16 volumio volumio[4798]: [Metrics] WebUI: 6s 534.56ms Sep 16 15:21:16 volumio volumio[4798]: info: CoreStateMachine::resetVolumioState Sep 16 15:21:16 volumio volumio[4798]: info: CoreStateMachine::getcurrentVolume Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:21:16 volumio volumio[4798]: info: MPD Permissions set Sep 16 15:21:16 volumio volumio[4798]: Forking 3 albumart workers Sep 16 15:21:16 volumio volumio-remote-updater[484]: [2025-09-16 15:21:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1758036073 101 Sep 16 15:21:16 volumio volumio[4798]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Sep 16 15:21:16 volumio volumio[4798]: info: MPD running with PID4842 Sep 16 15:21:16 volumio volumio[4798]: ,establishing connection Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:16 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:17 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:21:17 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:17 volumio volumio[4798]: error: updateQueue error: null Sep 16 15:21:17 volumio volumio[4798]: info: CoreStateMachine::setRepeat false single undefined Sep 16 15:21:17 volumio volumio[4798]: info: CoreStateMachine::pushState Sep 16 15:21:17 volumio volumio[4798]: info: CorePlayQueue::getTrack 0 Sep 16 15:21:17 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:21:17 volumio volumio[4798]: info: CoreCommandRouter::volumioPushState Sep 16 15:21:17 volumio volumio[4798]: info: CoreStateMachine::setRandom false Sep 16 15:21:17 volumio volumio[4798]: info: CoreStateMachine::pushState Sep 16 15:21:17 volumio volumio[4798]: info: CorePlayQueue::getTrack 0 Sep 16 15:21:17 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:21:17 volumio volumio[4798]: info: CoreCommandRouter::volumioPushState Sep 16 15:21:17 volumio volumio[4798]: info: Setting Device type: Raspberry PI Sep 16 15:21:17 volumio volumio[4798]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:21:17 volumio volumio[4798]: info: CoreStateMachine::pushState Sep 16 15:21:17 volumio volumio[4798]: info: CorePlayQueue::getTrack 0 Sep 16 15:21:17 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:21:17 volumio volumio[4798]: info: CoreCommandRouter::volumioPushState Sep 16 15:21:17 volumio volumio[4798]: info: CoreStateMachine::updateTrackBlock Sep 16 15:21:17 volumio volumio[4798]: info: CorePlayQueue::getTrackBlock Sep 16 15:21:17 volumio volumio[4798]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:21:17 volumio volumio[4798]: info: Completed loading Core Plugins Sep 16 15:21:17 volumio volumio[4798]: info: Preparing to generate the ALSA configuration file Sep 16 15:21:17 volumio volumio[4798]: error: updateQueue error: null Sep 16 15:21:17 volumio volumio[4798]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:21:17 volumio volumio[4798]: info: CoreStateMachine::pushState Sep 16 15:21:17 volumio volumio[4798]: info: CorePlayQueue::getTrack 0 Sep 16 15:21:17 volumio volumio[4798]: info: CoreCommandRouter::volumioPushState Sep 16 15:21:17 volumio volumio[4798]: info: Asound.conf file unchanged, so no further update is needed Sep 16 15:21:17 volumio volumio[4798]: info: Output device has changed, restarting MPD Sep 16 15:21:17 volumio volumio[4798]: info: Output device has changed, restarting Shairport Sync Sep 16 15:21:17 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:17 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:21:17 volumio sudo[4905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 15:21:17 volumio sudo[4906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 15:21:18 volumio sudo[4905]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:18 volumio sudo[4906]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:18 volumio sudo[4905]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:18 volumio systemd[1]: Stopping Music Player Daemon... Sep 16 15:21:18 volumio volumio[4798]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:21:18 volumio systemd[1]: mpd.service: Succeeded. Sep 16 15:21:18 volumio systemd[1]: Stopped Music Player Daemon. Sep 16 15:21:18 volumio volumio[4798]: info: ___________ START PLUGINS ___________ Sep 16 15:21:18 volumio systemd[1]: Starting Music Player Daemon... Sep 16 15:21:18 volumio volumio[4798]: Starting albumart workers Sep 16 15:21:18 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:21:18 volumio volumio[4798]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:21:18 volumio volumio[4798]: info: [1758036078193] CoreMusicLibrary::Adding element Media Servers Sep 16 15:21:18 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:21:18 volumio volumio[4798]: Cannot find translation for sourceMedia Servers Sep 16 15:21:18 volumio volumio[4798]: Starting albumart workers Sep 16 15:21:18 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:18 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:21:18 volumio volumio[4798]: Starting albumart workers Sep 16 15:21:18 volumio volumio[4798]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:21:18 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:18 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:21:18 volumio volumio[4798]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:21:18 volumio volumio[4798]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:21:18 volumio volumio[4798]: info: [1758036078539] CoreMusicLibrary::Adding element Last_100 Sep 16 15:21:18 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:21:18 volumio volumio[4798]: Cannot find translation for sourceMedia Servers Sep 16 15:21:18 volumio volumio[4798]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:21:18 volumio volumio[4798]: info: [1758036078544] CoreMusicLibrary::Adding element Webradio Sep 16 15:21:18 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:21:18 volumio volumio[4798]: Cannot find translation for sourceMedia Servers Sep 16 15:21:18 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 15:21:18 volumio volumio[4798]: info: [squeezelite_mc] Starting proxy server... Sep 16 15:21:18 volumio volumio[4798]: info: Volumio Calling Home Sep 16 15:21:18 volumio volumio[4798]: info: [squeezelite_mc] Proxy server started on port 36055 Sep 16 15:21:19 volumio volumio[4798]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:21:19 volumio volumio[4798]: Unhandled rejection Error: No sockets available, cannot start. Sep 16 15:21:19 volumio volumio[4798]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Sep 16 15:21:19 volumio volumio[4798]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Sep 16 15:21:19 volumio volumio[4798]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Sep 16 15:21:19 volumio volumio[4798]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Sep 16 15:21:19 volumio volumio[4798]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Sep 16 15:21:19 volumio volumio[4798]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Sep 16 15:21:19 volumio volumio[4798]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Sep 16 15:21:19 volumio volumio[4798]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Sep 16 15:21:19 volumio volumio[4798]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Sep 16 15:21:19 volumio volumio[4798]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Sep 16 15:21:19 volumio volumio[4798]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Sep 16 15:21:19 volumio volumio[4798]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 16 15:21:19 volumio volumio[4798]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 16 15:21:19 volumio volumio[4798]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Sep 16 15:21:19 volumio volumio[4798]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Sep 16 15:21:19 volumio volumio[4798]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 16 15:21:19 volumio volumio[4798]: info: MPD Permissions set Sep 16 15:21:19 volumio volumio[4798]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Sep 16 15:21:19 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:19 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:19 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:19 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:19 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:19 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:19 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:19 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:19 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:21:19 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:19 volumio volumio[4798]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:21:19 volumio volumio[4798]: info: CoreStateMachine::pushState Sep 16 15:21:19 volumio volumio[4798]: info: CorePlayQueue::getTrack 0 Sep 16 15:21:19 volumio volumio[4798]: info: CoreCommandRouter::volumioPushState Sep 16 15:21:19 volumio volumio[4798]: info: [squeezelite_mc] Server discovery started Sep 16 15:21:19 volumio volumio[4798]: info: [squeezelite_mc] Player finder started Sep 16 15:21:19 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:19 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:19 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:19 volumio volumio[4798]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:21:19 volumio volumio[4798]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Sep 16 15:21:19 volumio volumio[4798]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 15:21:19 volumio volumio[4798]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 16 15:21:19 volumio volumio[4798]: at doSend (dgram.js:692:16) Sep 16 15:21:19 volumio volumio[4798]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Sep 16 15:21:19 volumio volumio[4798]: at afterDns (dgram.js:638:5) Sep 16 15:21:19 volumio volumio[4798]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 16 15:21:19 volumio volumio[4798]: errno: -101, Sep 16 15:21:19 volumio volumio[4798]: code: 'ENETUNREACH', Sep 16 15:21:19 volumio volumio[4798]: syscall: 'send', Sep 16 15:21:19 volumio volumio[4798]: address: '255.255.255.255', Sep 16 15:21:19 volumio volumio[4798]: port: 3483 Sep 16 15:21:19 volumio volumio[4798]: } Sep 16 15:21:19 volumio volumio[4798]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 15:21:19 volumio mpd[4920]: Sep 16 15:21 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 15:21:20 volumio mpd[4920]: Sep 16 15:21 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 16 15:21:20 volumio mpd[4920]: Sep 16 15:21 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 16 15:21:20 volumio systemd[1]: Started Music Player Daemon. Sep 16 15:21:20 volumio sudo[4906]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:20 volumio sudo[4967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-16 15:20 Sep 16 15:21:20 volumio sudo[4967]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:20 volumio sudo[4967]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:20 volumio volumio-remote-updater[484]: [2025-09-16 15:21:20] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 16 15:21:20 volumio volumio-remote-updater[484]: [2025-09-16 15:21:20] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 16 15:21:20 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 16 15:21:20 volumio ntfs-3g[4851]: Unmounting /dev/sda1 (musica) Sep 16 15:21:20 volumio systemd[1]: media-musica.mount: Succeeded. Sep 16 15:21:20 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 16 15:21:20 volumio systemd[1]: Started dynamicswap service. Sep 16 15:21:20 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 16 15:21:21 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 16 15:21:21 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9. Sep 16 15:21:21 volumio systemd[1]: Stopped Volumio Backend Module. Sep 16 15:21:21 volumio systemd[1]: Started Volumio Backend Module. Sep 16 15:21:21 volumio systemd[1]: Started dynamicswap service. Sep 16 15:21:21 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 16 15:21:23 volumio volumio[4982]: info: ------------------------------------------- Sep 16 15:21:23 volumio volumio[4982]: info: ----- Volumio3 ---- Sep 16 15:21:23 volumio volumio[4982]: info: ------------------------------------------- Sep 16 15:21:23 volumio volumio[4982]: info: ----- System startup ---- Sep 16 15:21:23 volumio volumio[4982]: info: ------------------------------------------- Sep 16 15:21:23 volumio wpa_supplicant[992]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Telecom-15382809" Sep 16 15:21:23 volumio wpa_supplicant[992]: wlan0: Trying to associate with SSID 'Telecom-15382809' Sep 16 15:21:23 volumio volumio[4982]: info: MYVOLUMIO Environment detected Sep 16 15:21:23 volumio volumio[4982]: info: Plugin folders cleanup Sep 16 15:21:23 volumio volumio[4982]: info: Scanning into folder /volumio/app/plugins/ Sep 16 15:21:23 volumio volumio[4982]: info: Scanning category audio_interface Sep 16 15:21:23 volumio volumio[4982]: info: Scanning category miscellanea Sep 16 15:21:23 volumio volumio[4982]: info: Scanning category music_service Sep 16 15:21:23 volumio volumio[4982]: info: Scanning category plugins.json Sep 16 15:21:23 volumio volumio[4982]: info: Scanning category system_controller Sep 16 15:21:23 volumio volumio[4982]: info: Scanning category user_interface Sep 16 15:21:23 volumio volumio[4982]: info: Scanning into folder /data/plugins/ Sep 16 15:21:23 volumio volumio[4982]: info: Scanning category music_service Sep 16 15:21:23 volumio volumio[4982]: info: Plugin folders cleanup completed Sep 16 15:21:23 volumio volumio[4982]: info: ------------------------------------------- Sep 16 15:21:23 volumio volumio[4982]: info: ----- Core plugins startup ---- Sep 16 15:21:23 volumio volumio[4982]: info: ------------------------------------------- Sep 16 15:21:23 volumio volumio[4982]: info: Loading plugins from folder /volumio/app/plugins/ Sep 16 15:21:23 volumio volumio[4982]: info: Adding plugin upnp to MyMusic Plugins Sep 16 15:21:23 volumio volumio[4982]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 16 15:21:23 volumio volumio[4982]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 16 15:21:23 volumio volumio[4982]: info: Loading plugins from folder /data/plugins/ Sep 16 15:21:23 volumio volumio[4982]: info: Loading plugin "system"... Sep 16 15:21:23 volumio volumio[4982]: info: Loading plugin "appearance"... Sep 16 15:21:24 volumio volumio[4982]: info: Loading plugin "network"... Sep 16 15:21:24 volumio volumio[4982]: info: Refreshing Cached IP Addresses Sep 16 15:21:25 volumio sudo[5007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 16 15:21:25 volumio sudo[5007]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:25 volumio sudo[5007]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:25 volumio volumio[4982]: info: Loading plugin "services"... Sep 16 15:21:25 volumio sudo[5009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 16 15:21:25 volumio sudo[5009]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:25 volumio volumio[4982]: info: Loading plugin "alsa_controller"... Sep 16 15:21:25 volumio sudo[5009]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:25 volumio sudo[5013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 16 15:21:25 volumio sudo[5013]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:25 volumio sudo[5013]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:25 volumio kernel: ieee80211 phy0: brcmf_cfg80211_scan: Connecting: status (3) Sep 16 15:21:25 volumio volumio[4982]: info: Loading plugin "wizard"... Sep 16 15:21:25 volumio volumio[4982]: info: Loading plugin "volumio_command_line_client"... Sep 16 15:21:25 volumio volumio[4982]: info: Loading plugin "upnp"... Sep 16 15:21:25 volumio volumio[4982]: info: [1758036085189] Starting Upmpd Daemon Sep 16 15:21:25 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:21:25 volumio volumio[4982]: info: Loading plugin "my_music"... Sep 16 15:21:25 volumio volumio[4982]: info: Loading plugin "mpd"... Sep 16 15:21:25 volumio volumio[4982]: info: Creating MPD Configuration file Sep 16 15:21:25 volumio volumio[4982]: info: Loading plugin "upnp_browser"... Sep 16 15:21:25 volumio sudo[5020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 15:21:25 volumio sudo[5020]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:25 volumio sudo[5020]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:25 volumio sudo[5022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 15:21:25 volumio sudo[5022]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:25 volumio systemd[1]: Stopping Music Player Daemon... Sep 16 15:21:25 volumio systemd[1]: mpd.service: Succeeded. Sep 16 15:21:25 volumio systemd[1]: Stopped Music Player Daemon. Sep 16 15:21:25 volumio systemd[1]: Starting Music Player Daemon... Sep 16 15:21:25 volumio volumio-remote-updater[484]: [2025-09-16 15:21:25] [connect] Successful connection Sep 16 15:21:25 volumio volumio[4982]: info: Loading plugin "networkfs"... Sep 16 15:21:26 volumio volumio[4982]: info: Starting Udev Watcher for removable devices Sep 16 15:21:26 volumio dhcpcd[1004]: wlan0: carrier acquired Sep 16 15:21:26 volumio wpa_supplicant[992]: wlan0: Associated with 58:25:75:b2:9d:64 Sep 16 15:21:26 volumio wpa_supplicant[992]: wlan0: CTRL-EVENT-CONNECTED - Connection to 58:25:75:b2:9d:64 completed [id=0 id_str=] Sep 16 15:21:26 volumio wpa_supplicant[992]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 16 15:21:26 volumio dhcpcd[1004]: wlan0: IAID eb:7d:0c:90 Sep 16 15:21:26 volumio dhcpcd[1004]: wlan0: carrier lost Sep 16 15:21:26 volumio wpa_supplicant[992]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=IT Sep 16 15:21:26 volumio dhcpcd[1004]: wlan0: carrier acquired Sep 16 15:21:26 volumio dhcpcd[1004]: wlan0: IAID eb:7d:0c:90 Sep 16 15:21:26 volumio dhcpcd[1004]: wlan0: soliciting an IPv6 router Sep 16 15:21:26 volumio volumio[4982]: info: Ignoring mount for partition: boot Sep 16 15:21:26 volumio volumio[4982]: info: Ignoring mount for partition: volumio Sep 16 15:21:26 volumio volumio[4982]: info: Ignoring mount for partition: volumio_data Sep 16 15:21:26 volumio volumio[4982]: info: Mounting Device musica Sep 16 15:21:26 volumio sudo[5045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/musica -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 16 15:21:26 volumio sudo[5045]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:27 volumio mpd[5027]: Sep 16 15:21 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 15:21:27 volumio dhcpcd[1004]: wlan0: soliciting a DHCP lease Sep 16 15:21:27 volumio mpd[5027]: Sep 16 15:21 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 16 15:21:27 volumio mpd[5027]: Sep 16 15:21 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 16 15:21:27 volumio systemd[1]: Started Music Player Daemon. Sep 16 15:21:27 volumio sudo[5022]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:27 volumio ntfs-3g[5051]: Version 2017.3.23AR.3 integrated FUSE 28 Sep 16 15:21:27 volumio ntfs-3g[5051]: Mounted /dev/sda1 (Read-Write, label "musica", NTFS 3.1) Sep 16 15:21:27 volumio ntfs-3g[5051]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 16 15:21:27 volumio ntfs-3g[5051]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Sep 16 15:21:27 volumio ntfs-3g[5051]: Global ownership and permissions enforced, configuration type 7 Sep 16 15:21:27 volumio sudo[5045]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:27 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:21:27 volumio volumio[4982]: info: Loading plugin "alarm-clock"... Sep 16 15:21:27 volumio volumio[4982]: info: Loading plugin "airplay_emulation"... Sep 16 15:21:27 volumio volumio[4982]: info: Starting Shairport Sync Sep 16 15:21:27 volumio volumio[4982]: info: Loading plugin "last_100"... Sep 16 15:21:27 volumio volumio[4982]: info: Loading plugin "webradio"... Sep 16 15:21:27 volumio volumio[4982]: info: Loading plugin "i2s_dacs"... Sep 16 15:21:27 volumio volumio[4982]: info: I2S DAC not set, start Auto-detection Sep 16 15:21:27 volumio volumio[4982]: info: Loading plugin "volumiodiscovery"... Sep 16 15:21:27 volumio volumio[4982]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 15:21:27 volumio volumio[4982]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:21:27 volumio volumio[4982]: *** WARNING *** For more information see Sep 16 15:21:27 volumio node[4982]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 15:21:27 volumio volumio[4982]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 15:21:27 volumio volumio[4982]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:21:27 volumio volumio[4982]: *** WARNING *** For more information see Sep 16 15:21:27 volumio node[4982]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:21:27 volumio node[4982]: *** WARNING *** For more information see Sep 16 15:21:27 volumio node[4982]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 15:21:27 volumio node[4982]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 15:21:27 volumio node[4982]: *** WARNING *** For more information see Sep 16 15:21:27 volumio volumio[4982]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 16 15:21:27 volumio volumio[4982]: info: Discovery: Started advertising with name: Volumio Sep 16 15:21:27 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 15:21:27 volumio volumio[4982]: info: Loading plugin "squeezelite_mc"... Sep 16 15:21:28 volumio volumio[4982]: info: Loading plugin "outputs"... Sep 16 15:21:28 volumio volumio[4982]: info: Loading plugin "albumart"... Sep 16 15:21:28 volumio volumio[4982]: info: Plugin example_plugin is not enabled Sep 16 15:21:28 volumio volumio[4982]: info: Loading plugin "inputs"... Sep 16 15:21:28 volumio volumio[4982]: info: Loading plugin "updater_comm"... Sep 16 15:21:29 volumio volumio[4982]: info: Plugin mpdemulation is not enabled Sep 16 15:21:29 volumio volumio[4982]: info: Loading plugin "rest_api"... Sep 16 15:21:29 volumio volumio[4982]: info: Loading plugin "websocket"... Sep 16 15:21:29 volumio volumio[4982]: info: Loading i18n strings for locale it Sep 16 15:21:29 volumio volumio[4982]: Updating browse sources language Sep 16 15:21:29 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:21:29 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:21:29 volumio volumio[4982]: info: CoreCommandRouter::initPlayerControls Sep 16 15:21:29 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:29 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:29 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:29 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:29 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:29 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:29 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:29 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:29 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:21:29 volumio volumio[4982]: Express server listening on port 3000 Sep 16 15:21:29 volumio volumio[4982]: [Metrics] WebUI: 6s 683.51ms Sep 16 15:21:29 volumio volumio[4982]: info: CoreStateMachine::resetVolumioState Sep 16 15:21:29 volumio volumio[4982]: info: CoreStateMachine::getcurrentVolume Sep 16 15:21:29 volumio volumio[4982]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:21:29 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:21:29 volumio volumio[4982]: Forking 3 albumart workers Sep 16 15:21:29 volumio volumio[4982]: info: MPD Permissions set Sep 16 15:21:29 volumio volumio[4982]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 16 15:21:29 volumio volumio[4982]: wlan0 Interface doesn't support scanning : Resource temporarily unavailable Sep 16 15:21:29 volumio volumio[4982]: info: Cannot use regular scanning, forcing with ap-force Sep 16 15:21:29 volumio sudo[5078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 16 15:21:29 volumio sudo[5078]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:29 volumio dhcpcd[1004]: wlan0: offered 192.168.1.84 from 192.168.1.254 Sep 16 15:21:29 volumio dhcpcd[1004]: wlan0: probing address 192.168.1.84/24 Sep 16 15:21:30 volumio volumio[4982]: Starting albumart workers Sep 16 15:21:30 volumio volumio[4982]: Starting albumart workers Sep 16 15:21:30 volumio volumio[4982]: Starting albumart workers Sep 16 15:21:32 volumio sudo[5078]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:33 volumio volumio[4982]: info: MPD running with PID5027 Sep 16 15:21:33 volumio volumio[4982]: ,establishing connection Sep 16 15:21:33 volumio volumio[4982]: error: MPD error: The expression evaluated to a falsy value: Sep 16 15:21:33 volumio volumio[4982]: assert.ok(self.idling) Sep 16 15:21:33 volumio volumio[4982]: error: MPD error: The expression evaluated to a falsy value: Sep 16 15:21:33 volumio volumio[4982]: assert.ok(self.idling) Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:33 volumio volumio[4982]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:21:33 volumio volumio[4982]: info: CoreStateMachine::pushState Sep 16 15:21:33 volumio volumio[4982]: info: CorePlayQueue::getTrack 0 Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::volumioPushState Sep 16 15:21:33 volumio volumio[4982]: info: CoreStateMachine::updateTrackBlock Sep 16 15:21:33 volumio volumio[4982]: info: CorePlayQueue::getTrackBlock Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:21:33 volumio volumio[4982]: info: CoreStateMachine::setRepeat false single undefined Sep 16 15:21:33 volumio volumio[4982]: info: CoreStateMachine::pushState Sep 16 15:21:33 volumio volumio[4982]: info: CorePlayQueue::getTrack 0 Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::volumioPushState Sep 16 15:21:33 volumio volumio[4982]: info: CoreStateMachine::setRandom false Sep 16 15:21:33 volumio volumio[4982]: info: CoreStateMachine::pushState Sep 16 15:21:33 volumio volumio[4982]: info: CorePlayQueue::getTrack 0 Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::volumioPushState Sep 16 15:21:33 volumio volumio[4982]: info: Setting Device type: Raspberry PI Sep 16 15:21:33 volumio volumio[4982]: error: updateQueue error: null Sep 16 15:21:33 volumio volumio[4982]: info: Completed loading Core Plugins Sep 16 15:21:33 volumio volumio[4982]: info: Preparing to generate the ALSA configuration file Sep 16 15:21:33 volumio volumio[4982]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:21:33 volumio volumio[4982]: info: CoreStateMachine::pushState Sep 16 15:21:33 volumio volumio[4982]: info: CorePlayQueue::getTrack 0 Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::volumioPushState Sep 16 15:21:33 volumio volumio[4982]: info: Asound.conf file unchanged, so no further update is needed Sep 16 15:21:33 volumio volumio[4982]: info: Output device has changed, restarting MPD Sep 16 15:21:33 volumio sudo[5128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 15:21:33 volumio sudo[5128]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:33 volumio volumio[4982]: info: Output device has changed, restarting Shairport Sync Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:21:33 volumio sudo[5128]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:33 volumio sudo[5130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 15:21:33 volumio sudo[5130]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 15:21:33 volumio systemd[1]: Stopping Music Player Daemon... Sep 16 15:21:33 volumio volumio[4982]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:21:33 volumio volumio[4982]: info: ___________ START PLUGINS ___________ Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:21:33 volumio volumio[4982]: info: [1758036093508] CoreMusicLibrary::Adding element Media Servers Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:21:33 volumio volumio[4982]: Cannot find translation for sourceMedia Servers Sep 16 15:21:33 volumio systemd[1]: mpd.service: Succeeded. Sep 16 15:21:33 volumio systemd[1]: Stopped Music Player Daemon. Sep 16 15:21:33 volumio systemd[1]: Starting Music Player Daemon... Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:21:33 volumio volumio[4982]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:21:33 volumio volumio[4982]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:21:33 volumio volumio[4982]: info: [1758036093698] CoreMusicLibrary::Adding element Last_100 Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:21:33 volumio volumio[4982]: Cannot find translation for sourceMedia Servers Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 15:21:33 volumio volumio[4982]: info: [1758036093705] CoreMusicLibrary::Adding element Webradio Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 15:21:33 volumio volumio[4982]: Cannot find translation for sourceMedia Servers Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 15:21:33 volumio volumio[4982]: info: [squeezelite_mc] Starting proxy server... Sep 16 15:21:33 volumio volumio[4982]: info: Volumio Calling Home Sep 16 15:21:33 volumio volumio[4982]: info: [squeezelite_mc] Proxy server started on port 35461 Sep 16 15:21:33 volumio volumio[4982]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 15:21:34 volumio volumio[4982]: Unhandled rejection Error: No sockets available, cannot start. Sep 16 15:21:34 volumio volumio[4982]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Sep 16 15:21:34 volumio volumio[4982]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Sep 16 15:21:34 volumio volumio[4982]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Sep 16 15:21:34 volumio volumio[4982]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Sep 16 15:21:34 volumio volumio[4982]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Sep 16 15:21:34 volumio volumio[4982]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Sep 16 15:21:34 volumio volumio[4982]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Sep 16 15:21:34 volumio volumio[4982]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Sep 16 15:21:34 volumio volumio[4982]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Sep 16 15:21:34 volumio volumio[4982]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Sep 16 15:21:34 volumio volumio[4982]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Sep 16 15:21:34 volumio volumio[4982]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 16 15:21:34 volumio volumio[4982]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 16 15:21:34 volumio volumio[4982]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Sep 16 15:21:34 volumio volumio[4982]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Sep 16 15:21:34 volumio volumio[4982]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 16 15:21:34 volumio volumio[4982]: info: MPD Permissions set Sep 16 15:21:34 volumio volumio[4982]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Sep 16 15:21:34 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:34 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:34 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:34 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:34 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:34 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:34 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:34 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:34 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 15:21:34 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:34 volumio volumio[4982]: info: VolumeController:: Volume=38 Mute =false Sep 16 15:21:34 volumio volumio[4982]: info: CoreStateMachine::pushState Sep 16 15:21:34 volumio volumio[4982]: info: CorePlayQueue::getTrack 0 Sep 16 15:21:34 volumio volumio[4982]: info: CoreCommandRouter::volumioPushState Sep 16 15:21:34 volumio volumio[4982]: info: [squeezelite_mc] Server discovery started Sep 16 15:21:34 volumio volumio[4982]: info: [squeezelite_mc] Player finder started Sep 16 15:21:34 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:34 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:34 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 15:21:34 volumio volumio[4982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 15:21:34 volumio volumio[4982]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Sep 16 15:21:34 volumio volumio[4982]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 15:21:34 volumio volumio[4982]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 16 15:21:34 volumio volumio[4982]: at doSend (dgram.js:692:16) Sep 16 15:21:34 volumio volumio[4982]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Sep 16 15:21:34 volumio volumio[4982]: at afterDns (dgram.js:638:5) Sep 16 15:21:34 volumio volumio[4982]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 16 15:21:34 volumio volumio[4982]: errno: -101, Sep 16 15:21:34 volumio volumio[4982]: code: 'ENETUNREACH', Sep 16 15:21:34 volumio volumio[4982]: syscall: 'send', Sep 16 15:21:34 volumio volumio[4982]: address: '255.255.255.255', Sep 16 15:21:34 volumio volumio[4982]: port: 3483 Sep 16 15:21:34 volumio volumio[4982]: } Sep 16 15:21:34 volumio volumio[4982]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 15:21:34 volumio dhcpcd[1004]: wlan0: leased 192.168.1.84 for 86400 seconds Sep 16 15:21:34 volumio avahi-daemon[461]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.84. Sep 16 15:21:34 volumio dhcpcd[1004]: wlan0: adding route to 192.168.1.0/24 Sep 16 15:21:34 volumio dhcpcd[1004]: wlan0: adding default route via 192.168.1.254 Sep 16 15:21:34 volumio avahi-daemon[461]: New relevant interface wlan0.IPv4 for mDNS. Sep 16 15:21:34 volumio avahi-daemon[461]: Registering new address record for 192.168.1.84 on wlan0.IPv4. Sep 16 15:21:35 volumio mpd[5146]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 15:21:35 volumio mpd[5146]: config: Found database setting without music_directory - disabling database Sep 16 15:21:35 volumio mpd[5146]: output: No 'audio_output' defined in config file Sep 16 15:21:35 volumio mpd[5146]: output: Attempt to detect audio output device Sep 16 15:21:35 volumio mpd[5146]: output: Attempting to detect a alsa audio device Sep 16 15:21:35 volumio mpd[5146]: alsa_output: Error opening default ALSA device: Device or resource busy Sep 16 15:21:35 volumio mpd[5146]: output: Attempting to detect a oss audio device Sep 16 15:21:35 volumio mpd[5146]: oss_output: Error opening OSS device "/dev/dsp": No such file or directory Sep 16 15:21:35 volumio mpd[5146]: oss_output: Error opening OSS device "/dev/sound/dsp": No such file or directory Sep 16 15:21:35 volumio mpd[5146]: output: Attempting to detect a pulse audio device Sep 16 15:21:35 volumio mpd[5146]: output: Attempting to detect a jack audio device Sep 16 15:21:35 volumio mpd[5146]: output: Successfully detected a jack audio device Sep 16 15:21:35 volumio mpd[5146]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 16 15:21:35 volumio mpd[5146]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 16 15:21:35 volumio mpd[5146]: zeroconf: No global port, disabling zeroconf Sep 16 15:21:35 volumio systemd[1]: Started Music Player Daemon. Sep 16 15:21:35 volumio sudo[5130]: pam_unix(sudo:session): session closed for user root Sep 16 15:21:35 volumio sudo[5189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-16 15:20 Sep 16 15:21:35 volumio sudo[5189]: 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="1f061e035f7cb2799b32d3520aa8e55cd07df6f9" VOLUMIO_FE_VERSION="9971a09df9c3edfdedf2ecf438999be8aba9db68" VOLUMIO_FE3_VERSION="3974def988c7b2392ddc85f9b8cb4692d5e8fda9" VOLUMIO_BE_VERSION="b3c53a818b9b3d0e5c13c9d346c4d613c4ca426f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 11 Jul 2022 04:03:33 PM CEST" VOLUMIO_VERSION="3.324" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5b2f59e6e90e57f326ca25c7ca839d52"