-- Logs begin at Thu 2025-06-05 04:40:56 UTC, end at Mon 2025-06-09 00:54:11 UTC. -- Jun 09 00:53:00 deck volumio[7189]: Starting albumart workers Jun 09 00:53:00 deck volumio[7189]: Starting albumart workers Jun 09 00:53:00 deck volumio[7189]: info: Applying required configuration parameters for plugin motherearthradio Jun 09 00:53:00 deck volumio[7189]: info: [1749430380190] [MotherEarth] API delay: 5 Jun 09 00:53:00 deck volumio[7189]: info: Loading i18n strings for locale en Jun 09 00:53:00 deck volumio[7189]: Starting albumart workers Jun 09 00:53:00 deck volumio[7189]: Updating browse sources language Jun 09 00:53:00 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:00 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 00:53:00 deck volumio[7189]: info: CoreCommandRouter::initPlayerControls Jun 09 00:53:00 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:00 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:00 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:00 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:00 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:00 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:00 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:00 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:00 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 00:53:00 deck volumio[7189]: Express server listening on port 3000 Jun 09 00:53:00 deck volumio[7189]: [Metrics] WebUI: 8s 426.51ms Jun 09 00:53:00 deck volumio[7189]: info: CoreStateMachine::resetVolumioState Jun 09 00:53:00 deck volumio[7189]: info: CoreStateMachine::getcurrentVolume Jun 09 00:53:00 deck volumio[7189]: info: CoreCommandRouter::volumioRetrievevolume Jun 09 00:53:00 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:00 deck volumio[7189]: info: Cannot mount NAS Local at system boot, trial number 2 ,retrying in 5 seconds Jun 09 00:53:00 deck volumio[7189]: info: Volumio Network Manager: Network status updated: 0 Jun 09 00:53:00 deck volumio[7189]: info: Reloading queue from file Jun 09 00:53:01 deck volumio[7189]: info: CoreStateMachine::setRepeat true single undefined Jun 09 00:53:01 deck volumio[7189]: info: CoreStateMachine::pushState Jun 09 00:53:01 deck volumio[7189]: info: CorePlayQueue::getTrack 0 Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::volumioPushState Jun 09 00:53:01 deck volumio[7189]: info: CoreStateMachine::setRandom null Jun 09 00:53:01 deck volumio[7189]: info: CoreStateMachine::pushState Jun 09 00:53:01 deck volumio[7189]: info: CorePlayQueue::getTrack 0 Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::volumioPushState Jun 09 00:53:01 deck volumio[7189]: info: Setting Device type: Raspberry PI Jun 09 00:53:01 deck volumio[7189]: info: VolumeController:: Volume=70 Mute =false Jun 09 00:53:01 deck volumio[7189]: info: CoreStateMachine::pushState Jun 09 00:53:01 deck volumio[7189]: info: CorePlayQueue::getTrack 0 Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::volumioPushState Jun 09 00:53:01 deck volumio[7189]: info: CoreStateMachine::updateTrackBlock Jun 09 00:53:01 deck volumio[7189]: info: CorePlayQueue::getTrackBlock Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::volumioRetrievevolume Jun 09 00:53:01 deck volumio[7189]: info: Completed loading Core Plugins Jun 09 00:53:01 deck volumio[7189]: info: Preparing to generate the ALSA configuration file Jun 09 00:53:01 deck volumio[7189]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 09 00:53:01 deck volumio[7189]: info: Reading ALSA contributions from plugins. Jun 09 00:53:01 deck volumio[7189]: info: Asound.conf file unchanged, so no further update is needed Jun 09 00:53:01 deck volumio[7189]: info: Output device has changed, restarting MPD Jun 09 00:53:01 deck sudo[7315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 09 00:53:01 deck sudo[7315]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:01 deck volumio[7189]: info: Output device has changed, restarting Shairport Sync Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:01 deck sudo[7315]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:01 deck sudo[7318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 09 00:53:01 deck sudo[7318]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:01 deck systemd[1]: Stopping Music Player Daemon... Jun 09 00:53:01 deck volumio[7189]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 09 00:53:01 deck volumio[7189]: info: ___________ START PLUGINS ___________ Jun 09 00:53:01 deck systemd[1]: mpd.service: Succeeded. Jun 09 00:53:01 deck systemd[1]: Stopped Music Player Daemon. Jun 09 00:53:01 deck volumio[7189]: info: ControllerMpd::onStart: Initializing MPD Jun 09 00:53:01 deck volumio[7189]: info: Creating MPD Configuration file Jun 09 00:53:01 deck sudo[7326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 09 00:53:01 deck sudo[7326]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 00:53:01 deck volumio[7189]: info: [1749430381330] CoreMusicLibrary::Adding element Media Servers Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:01 deck sudo[7326]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:01 deck systemd[1]: Starting Music Player Daemon... Jun 09 00:53:01 deck sudo[7329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 09 00:53:01 deck sudo[7329]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:01 deck systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 09 00:53:01 deck systemd[1]: mpd.service: Succeeded. Jun 09 00:53:01 deck systemd[1]: Stopped Music Player Daemon. Jun 09 00:53:01 deck systemd[1]: Starting Music Player Daemon... Jun 09 00:53:01 deck volumio[7189]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:01 deck volumio[7189]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 00:53:01 deck volumio[7189]: info: [1749430381492] CoreMusicLibrary::Adding element Last_100 Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 00:53:01 deck volumio[7189]: info: [1749430381496] CoreMusicLibrary::Adding element Webradio Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 00:53:01 deck volumio[7189]: info: Initializing BBC Radios Jun 09 00:53:01 deck sudo[7334]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 09 00:53:01 deck sudo[7334]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:01 deck sudo[7334]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 00:53:01 deck volumio[7189]: info: [1749430381599] CoreMusicLibrary::Adding element SoundCloud Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:01 deck volumio[7189]: Cannot find translation for source SoundCloud Jun 09 00:53:01 deck volumio[7189]: info: Checking for old spotify connect plugin installed Jun 09 00:53:01 deck volumio[7189]: info: [squeezelite_mc] Starting proxy server... Jun 09 00:53:01 deck volumio[7189]: info: Loading i18n strings for locale en Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:01 deck volumio[7189]: info: FusionDsp - mixtype--------------------- Hardware Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 00:53:01 deck volumio[7189]: info: [1749430381697] CoreMusicLibrary::Adding element Mother Earth Radio Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:01 deck volumio[7189]: Cannot find translation for source SoundCloud Jun 09 00:53:01 deck volumio[7189]: Cannot find translation for source Mother Earth Radio Jun 09 00:53:01 deck volumio[7189]: info: Volumio Calling Home Jun 09 00:53:01 deck sudo[7351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jun 09 00:53:01 deck sudo[7351]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:01 deck sudo[7351]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 09 00:53:01 deck volumio[7189]: [SpotifyConnect] Creating VLS config file Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:01 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:01 deck volumio[7189]: [SpotifyConnect] Starting metadata listener Jun 09 00:53:01 deck volumio[7189]: info: [squeezelite_mc] Proxy server started on port 41315 Jun 09 00:53:01 deck volumio[7189]: info: Preparing to generate the ALSA configuration file Jun 09 00:53:01 deck sudo[7357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jun 09 00:53:01 deck sudo[7357]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:01 deck sudo[7357]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::volumioRetrievevolume Jun 09 00:53:02 deck volumio[7189]: Unhandled rejection Error: No sockets available, cannot start. Jun 09 00:53:02 deck volumio[7189]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jun 09 00:53:02 deck volumio[7189]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jun 09 00:53:02 deck volumio[7189]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jun 09 00:53:02 deck volumio[7189]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jun 09 00:53:02 deck volumio[7189]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jun 09 00:53:02 deck volumio[7189]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jun 09 00:53:02 deck volumio[7189]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jun 09 00:53:02 deck volumio[7189]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jun 09 00:53:02 deck volumio[7189]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jun 09 00:53:02 deck volumio[7189]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jun 09 00:53:02 deck volumio[7189]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jun 09 00:53:02 deck volumio[7189]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 09 00:53:02 deck volumio[7189]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 09 00:53:02 deck volumio[7189]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jun 09 00:53:02 deck volumio[7189]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jun 09 00:53:02 deck volumio[7189]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 09 00:53:02 deck volumio[7189]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 09 00:53:02 deck volumio[7189]: info: Reading ALSA contributions from plugins. Jun 09 00:53:02 deck wpa_supplicant[868]: wlan0: Failed to initiate sched scan Jun 09 00:53:02 deck volumio[7189]: [SpotifyConnect] Vollibrespot Daemon service started! Jun 09 00:53:02 deck volumio[7189]: [Metrics] SpotifyConnect: 0s 290.40ms Jun 09 00:53:02 deck volumio[7189]: info: MPD Permissions set Jun 09 00:53:02 deck volumio[7189]: info: MPD Permissions set Jun 09 00:53:02 deck volumio[7189]: info: VolumeController:: Volume=70 Mute =false Jun 09 00:53:02 deck volumio[7189]: info: CoreStateMachine::pushState Jun 09 00:53:02 deck volumio[7189]: info: CorePlayQueue::getTrack 0 Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::volumioPushState Jun 09 00:53:02 deck volumio[7189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 09 00:53:02 deck volumio[7189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: VolumeController:: Volume=70 Mute =false Jun 09 00:53:02 deck volumio[7189]: info: CoreStateMachine::pushState Jun 09 00:53:02 deck volumio[7189]: info: CorePlayQueue::getTrack 0 Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::volumioPushState Jun 09 00:53:02 deck volumio[7189]: info: [squeezelite_mc] Server discovery started Jun 09 00:53:02 deck volumio[7189]: info: [squeezelite_mc] Player finder started Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:02 deck volumio[7189]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 09 00:53:02 deck volumio[7189]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 00:53:02 deck volumio[7189]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 09 00:53:02 deck volumio[7189]: at doSend (dgram.js:692:16) Jun 09 00:53:02 deck volumio[7189]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 09 00:53:02 deck volumio[7189]: at afterDns (dgram.js:638:5) Jun 09 00:53:02 deck volumio[7189]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 09 00:53:02 deck volumio[7189]: errno: -101, Jun 09 00:53:02 deck volumio[7189]: code: 'ENETUNREACH', Jun 09 00:53:02 deck volumio[7189]: syscall: 'send', Jun 09 00:53:02 deck volumio[7189]: address: '255.255.255.255', Jun 09 00:53:02 deck volumio[7189]: port: 3483 Jun 09 00:53:02 deck volumio[7189]: } Jun 09 00:53:02 deck volumio[7189]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 00:53:03 deck sudo[7380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-09 00:52 Jun 09 00:53:03 deck sudo[7380]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:03 deck sudo[7380]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:03 deck mpd[7341]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 09 00:53:03 deck mpd[7341]: output: No 'audio_output' defined in config file Jun 09 00:53:03 deck systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 09 00:53:03 deck sudo[7318]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:03 deck sudo[7329]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:03 deck mpd[7341]: output: Successfully detected a sndio audio device Jun 09 00:53:03 deck mpd[7341]: zeroconf: No global port, disabling zeroconf Jun 09 00:53:03 deck systemd[1]: Started Music Player Daemon. Jun 09 00:53:03 deck systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 09 00:53:03 deck systemd[1]: Started dynamicswap service. Jun 09 00:53:03 deck systemd[1]: dynamicswap.service: Succeeded. Jun 09 00:53:03 deck systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 09 00:53:03 deck systemd[1]: volumio.service: Scheduled restart job, restart counter is at 17. Jun 09 00:53:03 deck systemd[1]: Started dynamicswap service. Jun 09 00:53:03 deck systemd[1]: Stopped Volumio Backend Module. Jun 09 00:53:03 deck systemd[1]: Started Volumio Backend Module. Jun 09 00:53:03 deck systemd[1]: dynamicswap.service: Succeeded. Jun 09 00:53:05 deck volumio[7396]: info: ------------------------------------------- Jun 09 00:53:05 deck volumio[7396]: info: ----- Volumio3 ---- Jun 09 00:53:05 deck volumio[7396]: info: ------------------------------------------- Jun 09 00:53:05 deck volumio[7396]: info: ----- System startup ---- Jun 09 00:53:05 deck volumio[7396]: info: ------------------------------------------- Jun 09 00:53:06 deck volumio[7396]: info: MYVOLUMIO Environment detected Jun 09 00:53:06 deck volumio[7396]: info: Plugin folders cleanup Jun 09 00:53:06 deck volumio[7396]: info: Scanning into folder /volumio/app/plugins/ Jun 09 00:53:06 deck volumio[7396]: info: Scanning category audio_interface Jun 09 00:53:06 deck volumio[7396]: info: Scanning category miscellanea Jun 09 00:53:06 deck volumio[7396]: info: Scanning category music_service Jun 09 00:53:06 deck volumio[7396]: info: Scanning category plugins.json Jun 09 00:53:06 deck volumio[7396]: info: Scanning category system_controller Jun 09 00:53:06 deck volumio[7396]: info: Scanning category user_interface Jun 09 00:53:06 deck volumio[7396]: info: Scanning into folder /data/plugins/ Jun 09 00:53:06 deck volumio[7396]: info: Scanning category audio_interface Jun 09 00:53:06 deck volumio[7396]: info: Scanning category music_service Jun 09 00:53:06 deck volumio[7396]: info: Plugin folders cleanup completed Jun 09 00:53:06 deck volumio[7396]: info: ------------------------------------------- Jun 09 00:53:06 deck volumio[7396]: info: ----- Core plugins startup ---- Jun 09 00:53:06 deck volumio[7396]: info: ------------------------------------------- Jun 09 00:53:06 deck volumio[7396]: info: Loading plugins from folder /volumio/app/plugins/ Jun 09 00:53:06 deck volumio[7396]: info: Adding plugin upnp to MyMusic Plugins Jun 09 00:53:06 deck volumio[7396]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 09 00:53:06 deck volumio[7396]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 09 00:53:06 deck volumio[7396]: info: Loading plugins from folder /data/plugins/ Jun 09 00:53:06 deck volumio[7396]: info: Loading plugin "system"... Jun 09 00:53:06 deck volumio[7396]: info: Loading plugin "appearance"... Jun 09 00:53:07 deck volumio[7396]: info: Loading plugin "network"... Jun 09 00:53:07 deck volumio[7396]: info: Refreshing Cached IP Addresses Jun 09 00:53:07 deck sudo[7423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 09 00:53:07 deck sudo[7423]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:07 deck sudo[7423]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:07 deck sudo[7425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 09 00:53:07 deck sudo[7425]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:07 deck sudo[7425]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:07 deck volumio[7396]: info: Loading plugin "services"... Jun 09 00:53:07 deck volumio[7396]: info: Loading plugin "alsa_controller"... Jun 09 00:53:07 deck sudo[7435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 09 00:53:07 deck sudo[7435]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:07 deck sudo[7435]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:07 deck wpa_supplicant[868]: wlan0: Failed to initiate sched scan Jun 09 00:53:07 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 00:53:07 deck volumio[7396]: info: Loading plugin "wizard"... Jun 09 00:53:07 deck volumio[7396]: info: Loading plugin "networkfs"... Jun 09 00:53:07 deck volumio[7396]: info: Cannot mount NAS Local at system boot, trial number 1 ,retrying in 5 seconds Jun 09 00:53:07 deck volumio[7396]: info: Starting Udev Watcher for removable devices Jun 09 00:53:07 deck volumio[7396]: info: Ignoring mount for partition: boot Jun 09 00:53:07 deck volumio[7396]: info: Ignoring mount for partition: volumio Jun 09 00:53:07 deck volumio[7396]: info: Ignoring mount for partition: volumio_data Jun 09 00:53:07 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 09 00:53:07 deck volumio[7396]: info: Loading plugin "volumio_command_line_client"... Jun 09 00:53:07 deck volumio[7396]: info: Loading plugin "upnp"... Jun 09 00:53:07 deck volumio[7396]: info: [1749430387800] Starting Upmpd Daemon Jun 09 00:53:07 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 09 00:53:07 deck volumio[7396]: info: Loading plugin "my_music"... Jun 09 00:53:07 deck volumio[7396]: info: Loading plugin "mpd"... Jun 09 00:53:08 deck volumio[7396]: info: Loading plugin "upnp_browser"... Jun 09 00:53:08 deck volumio-remote-updater[577]: [2025-06-09 00:53:08] [connect] Successful connection Jun 09 00:53:08 deck volumio[7396]: info: Loading plugin "alarm-clock"... Jun 09 00:53:08 deck volumio[7396]: info: Loading plugin "airplay_emulation"... Jun 09 00:53:08 deck volumio[7396]: info: Starting Shairport Sync Jun 09 00:53:08 deck volumio[7396]: info: Loading plugin "last_100"... Jun 09 00:53:08 deck volumio[7396]: info: Loading plugin "webradio"... Jun 09 00:53:08 deck volumio[7396]: info: Loading plugin "i2s_dacs"... Jun 09 00:53:08 deck volumio[7396]: info: I2S DAC not set, start Auto-detection Jun 09 00:53:08 deck volumio[7396]: info: Loading plugin "volumiodiscovery"... Jun 09 00:53:08 deck volumio[7396]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 09 00:53:08 deck volumio[7396]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 09 00:53:08 deck volumio[7396]: *** WARNING *** For more information see Jun 09 00:53:08 deck node[7396]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 09 00:53:08 deck volumio[7396]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 09 00:53:08 deck volumio[7396]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 09 00:53:08 deck volumio[7396]: *** WARNING *** For more information see Jun 09 00:53:08 deck node[7396]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 09 00:53:08 deck node[7396]: *** WARNING *** For more information see Jun 09 00:53:08 deck node[7396]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 09 00:53:08 deck node[7396]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 09 00:53:08 deck node[7396]: *** WARNING *** For more information see Jun 09 00:53:08 deck volumio[7396]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 09 00:53:08 deck volumio[7396]: info: Discovery: Started advertising with name: Deck Jun 09 00:53:08 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 09 00:53:08 deck volumio[7396]: info: Loading plugin "soundcloud"... Jun 09 00:53:09 deck volumio[7396]: info: Loading plugin "spop"... Jun 09 00:53:10 deck volumio[7396]: info: Loading plugin "squeezelite_mc"... Jun 09 00:53:11 deck volumio[7396]: info: Loading plugin "outputs"... Jun 09 00:53:11 deck volumio[7396]: info: Loading plugin "albumart"... Jun 09 00:53:11 deck volumio[7396]: info: Plugin example_plugin is not enabled Jun 09 00:53:11 deck volumio[7396]: info: Loading plugin "inputs"... Jun 09 00:53:11 deck volumio[7396]: info: Loading plugin "updater_comm"... Jun 09 00:53:11 deck volumio[7396]: info: Plugin mpdemulation is not enabled Jun 09 00:53:11 deck volumio[7396]: info: Loading plugin "rest_api"... Jun 09 00:53:11 deck volumio[7396]: info: Loading plugin "websocket"... Jun 09 00:53:11 deck volumio[7396]: info: Starting Socket.io Server version 2.3.0 Jun 09 00:53:11 deck volumio[7396]: info: Loading plugin "fusiondsp"... Jun 09 00:53:11 deck volumio[7396]: Forking 3 albumart workers Jun 09 00:53:11 deck volumio[7396]: info: Applying required configuration parameters for plugin fusiondsp Jun 09 00:53:11 deck volumio[7396]: info: Loading plugin "RoonBridge"... Jun 09 00:53:12 deck volumio[7396]: info: Applying required configuration parameters for plugin RoonBridge Jun 09 00:53:12 deck volumio[7396]: info: Loading plugin "motherearthradio"... Jun 09 00:53:12 deck volumio[7396]: info: Applying required configuration parameters for plugin motherearthradio Jun 09 00:53:12 deck volumio[7396]: info: [1749430392563] [MotherEarth] API delay: 5 Jun 09 00:53:12 deck volumio[7396]: info: Loading i18n strings for locale en Jun 09 00:53:12 deck volumio[7396]: Updating browse sources language Jun 09 00:53:12 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:12 deck volumio[7396]: Starting albumart workers Jun 09 00:53:12 deck volumio[7396]: Starting albumart workers Jun 09 00:53:12 deck volumio[7396]: Starting albumart workers Jun 09 00:53:12 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 00:53:12 deck volumio[7396]: info: CoreCommandRouter::initPlayerControls Jun 09 00:53:12 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:12 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:12 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:12 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:12 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:12 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:12 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:12 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:12 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 00:53:12 deck volumio[7396]: Express server listening on port 3000 Jun 09 00:53:12 deck volumio[7396]: [Metrics] WebUI: 8s 53.39ms Jun 09 00:53:12 deck volumio[7396]: info: CoreStateMachine::resetVolumioState Jun 09 00:53:12 deck volumio[7396]: info: CoreStateMachine::getcurrentVolume Jun 09 00:53:12 deck volumio[7396]: info: CoreCommandRouter::volumioRetrievevolume Jun 09 00:53:13 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:13 deck volumio[7396]: info: Cannot mount NAS Local at system boot, trial number 2 ,retrying in 5 seconds Jun 09 00:53:13 deck volumio[7396]: info: Volumio Network Manager: Network status updated: 0 Jun 09 00:53:13 deck wpa_supplicant[868]: wlan0: Failed to initiate sched scan Jun 09 00:53:13 deck volumio[7396]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 09 00:53:13 deck volumio[7396]: wlan0 Interface doesn't support scanning : Device or resource busy Jun 09 00:53:13 deck volumio[7396]: info: Cannot use regular scanning, forcing with ap-force Jun 09 00:53:13 deck sudo[7487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 09 00:53:13 deck sudo[7487]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:13 deck sudo[7487]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:13 deck volumio[7396]: info: VolumeController:: Volume=70 Mute =false Jun 09 00:53:13 deck volumio[7396]: info: CoreStateMachine::pushState Jun 09 00:53:13 deck volumio[7396]: info: CorePlayQueue::getTrack 0 Jun 09 00:53:13 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 00:53:13 deck volumio[7396]: info: CoreCommandRouter::volumioPushState Jun 09 00:53:13 deck volumio[7396]: info: CoreStateMachine::updateTrackBlock Jun 09 00:53:13 deck volumio[7396]: info: CorePlayQueue::getTrackBlock Jun 09 00:53:13 deck volumio[7396]: info: CoreCommandRouter::volumioRetrievevolume Jun 09 00:53:13 deck volumio[7396]: info: Reloading queue from file Jun 09 00:53:13 deck volumio[7396]: info: CoreStateMachine::setRepeat true single undefined Jun 09 00:53:13 deck volumio[7396]: info: CoreStateMachine::pushState Jun 09 00:53:13 deck volumio[7396]: info: CorePlayQueue::getTrack 0 Jun 09 00:53:13 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 00:53:13 deck volumio[7396]: info: CoreCommandRouter::volumioPushState Jun 09 00:53:13 deck volumio[7396]: info: CoreStateMachine::setRandom null Jun 09 00:53:13 deck volumio[7396]: info: CoreStateMachine::pushState Jun 09 00:53:13 deck volumio[7396]: info: CorePlayQueue::getTrack 0 Jun 09 00:53:13 deck volumio[7396]: info: CoreCommandRouter::volumioPushState Jun 09 00:53:13 deck volumio[7396]: info: Setting Device type: Raspberry PI Jun 09 00:53:14 deck volumio[7396]: info: Completed loading Core Plugins Jun 09 00:53:14 deck volumio[7396]: info: Preparing to generate the ALSA configuration file Jun 09 00:53:14 deck volumio[7396]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 09 00:53:14 deck volumio[7396]: info: Reading ALSA contributions from plugins. Jun 09 00:53:14 deck volumio[7396]: info: VolumeController:: Volume=70 Mute =false Jun 09 00:53:14 deck volumio[7396]: info: CoreStateMachine::pushState Jun 09 00:53:14 deck volumio[7396]: info: CorePlayQueue::getTrack 0 Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::volumioPushState Jun 09 00:53:14 deck volumio[7396]: info: Asound.conf file unchanged, so no further update is needed Jun 09 00:53:14 deck volumio[7396]: info: Output device has changed, restarting MPD Jun 09 00:53:14 deck sudo[7520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 09 00:53:14 deck sudo[7520]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:14 deck volumio[7396]: info: Output device has changed, restarting Shairport Sync Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:14 deck sudo[7520]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:14 deck sudo[7523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 09 00:53:14 deck sudo[7523]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:14 deck systemd[1]: Stopping Music Player Daemon... Jun 09 00:53:14 deck volumio[7396]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 09 00:53:14 deck volumio[7396]: info: ___________ START PLUGINS ___________ Jun 09 00:53:14 deck volumio[7396]: info: ControllerMpd::onStart: Initializing MPD Jun 09 00:53:14 deck volumio[7396]: info: Creating MPD Configuration file Jun 09 00:53:14 deck systemd[1]: mpd.service: Succeeded. Jun 09 00:53:14 deck systemd[1]: Stopped Music Player Daemon. Jun 09 00:53:14 deck sudo[7530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 09 00:53:14 deck sudo[7530]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 00:53:14 deck volumio[7396]: info: [1749430394234] CoreMusicLibrary::Adding element Media Servers Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:14 deck sudo[7530]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:14 deck systemd[1]: Starting Music Player Daemon... Jun 09 00:53:14 deck sudo[7532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 09 00:53:14 deck sudo[7532]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:14 deck systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 09 00:53:14 deck systemd[1]: mpd.service: Succeeded. Jun 09 00:53:14 deck systemd[1]: Stopped Music Player Daemon. Jun 09 00:53:14 deck systemd[1]: Starting Music Player Daemon... Jun 09 00:53:14 deck volumio[7396]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:14 deck volumio[7396]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 00:53:14 deck volumio[7396]: info: [1749430394396] CoreMusicLibrary::Adding element Last_100 Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 00:53:14 deck volumio[7396]: info: [1749430394400] CoreMusicLibrary::Adding element Webradio Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 00:53:14 deck volumio[7396]: info: Initializing BBC Radios Jun 09 00:53:14 deck sudo[7535]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 09 00:53:14 deck sudo[7535]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:14 deck sudo[7535]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 00:53:14 deck volumio[7396]: info: [1749430394503] CoreMusicLibrary::Adding element SoundCloud Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:14 deck volumio[7396]: Cannot find translation for source SoundCloud Jun 09 00:53:14 deck volumio[7396]: info: Checking for old spotify connect plugin installed Jun 09 00:53:14 deck volumio[7396]: info: [squeezelite_mc] Starting proxy server... Jun 09 00:53:14 deck volumio[7396]: info: Loading i18n strings for locale en Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:14 deck volumio[7396]: info: FusionDsp - mixtype--------------------- Hardware Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 00:53:14 deck volumio[7396]: info: [1749430394591] CoreMusicLibrary::Adding element Mother Earth Radio Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:14 deck volumio[7396]: Cannot find translation for source SoundCloud Jun 09 00:53:14 deck volumio[7396]: Cannot find translation for source Mother Earth Radio Jun 09 00:53:14 deck volumio[7396]: info: Volumio Calling Home Jun 09 00:53:14 deck sudo[7555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jun 09 00:53:14 deck sudo[7555]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:14 deck sudo[7555]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 09 00:53:14 deck volumio[7396]: [SpotifyConnect] Creating VLS config file Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:14 deck volumio[7396]: [SpotifyConnect] Starting metadata listener Jun 09 00:53:14 deck volumio[7396]: info: [squeezelite_mc] Proxy server started on port 33439 Jun 09 00:53:14 deck volumio[7396]: info: Preparing to generate the ALSA configuration file Jun 09 00:53:14 deck sudo[7561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jun 09 00:53:14 deck sudo[7561]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:14 deck sudo[7561]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:14 deck volumio[7396]: info: CoreCommandRouter::volumioRetrievevolume Jun 09 00:53:14 deck volumio[7396]: Unhandled rejection Error: No sockets available, cannot start. Jun 09 00:53:14 deck volumio[7396]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jun 09 00:53:14 deck volumio[7396]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jun 09 00:53:14 deck volumio[7396]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jun 09 00:53:14 deck volumio[7396]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jun 09 00:53:14 deck volumio[7396]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jun 09 00:53:14 deck volumio[7396]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jun 09 00:53:14 deck volumio[7396]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jun 09 00:53:14 deck volumio[7396]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jun 09 00:53:14 deck volumio[7396]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jun 09 00:53:14 deck volumio[7396]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jun 09 00:53:14 deck volumio[7396]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jun 09 00:53:14 deck volumio[7396]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 09 00:53:14 deck volumio[7396]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 09 00:53:14 deck volumio[7396]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jun 09 00:53:14 deck volumio[7396]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jun 09 00:53:14 deck volumio[7396]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 09 00:53:14 deck volumio[7396]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 09 00:53:14 deck volumio[7396]: info: Reading ALSA contributions from plugins. Jun 09 00:53:15 deck volumio[7396]: [SpotifyConnect] Vollibrespot Daemon service started! Jun 09 00:53:15 deck volumio[7396]: [Metrics] SpotifyConnect: 0s 250.92ms Jun 09 00:53:15 deck volumio[7396]: info: MPD Permissions set Jun 09 00:53:15 deck volumio[7396]: info: MPD Permissions set Jun 09 00:53:15 deck volumio[7396]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 09 00:53:15 deck volumio[7396]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 09 00:53:15 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:15 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:15 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:15 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:15 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:15 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:15 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:15 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:15 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:15 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:15 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:15 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:15 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:15 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:15 deck volumio[7396]: info: VolumeController:: Volume=70 Mute =false Jun 09 00:53:15 deck volumio[7396]: info: CoreStateMachine::pushState Jun 09 00:53:15 deck volumio[7396]: info: CorePlayQueue::getTrack 0 Jun 09 00:53:15 deck volumio[7396]: info: CoreCommandRouter::volumioPushState Jun 09 00:53:15 deck volumio[7396]: info: [squeezelite_mc] Server discovery started Jun 09 00:53:15 deck volumio[7396]: info: [squeezelite_mc] Player finder started Jun 09 00:53:15 deck volumio[7396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:15 deck volumio[7396]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 09 00:53:15 deck volumio[7396]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 00:53:15 deck volumio[7396]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 09 00:53:15 deck volumio[7396]: at doSend (dgram.js:692:16) Jun 09 00:53:15 deck volumio[7396]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 09 00:53:15 deck volumio[7396]: at afterDns (dgram.js:638:5) Jun 09 00:53:15 deck volumio[7396]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 09 00:53:15 deck volumio[7396]: errno: -101, Jun 09 00:53:15 deck volumio[7396]: code: 'ENETUNREACH', Jun 09 00:53:15 deck volumio[7396]: syscall: 'send', Jun 09 00:53:15 deck volumio[7396]: address: '255.255.255.255', Jun 09 00:53:15 deck volumio[7396]: port: 3483 Jun 09 00:53:15 deck volumio[7396]: } Jun 09 00:53:15 deck volumio[7396]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 00:53:16 deck sudo[7584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-09 00:52 Jun 09 00:53:16 deck sudo[7584]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:16 deck sudo[7584]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:16 deck mpd[7545]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 09 00:53:16 deck mpd[7545]: output: No 'audio_output' defined in config file Jun 09 00:53:16 deck systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 09 00:53:16 deck sudo[7523]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:16 deck sudo[7532]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:16 deck mpd[7545]: output: Successfully detected a sndio audio device Jun 09 00:53:16 deck mpd[7545]: zeroconf: No global port, disabling zeroconf Jun 09 00:53:16 deck systemd[1]: Started Music Player Daemon. Jun 09 00:53:16 deck systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 09 00:53:16 deck systemd[1]: Started dynamicswap service. Jun 09 00:53:16 deck systemd[1]: dynamicswap.service: Succeeded. Jun 09 00:53:16 deck systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 09 00:53:16 deck systemd[1]: volumio.service: Scheduled restart job, restart counter is at 18. Jun 09 00:53:16 deck systemd[1]: Stopped Volumio Backend Module. Jun 09 00:53:16 deck systemd[1]: Started Volumio Backend Module. Jun 09 00:53:16 deck systemd[1]: Started dynamicswap service. Jun 09 00:53:16 deck systemd[1]: dynamicswap.service: Succeeded. Jun 09 00:53:18 deck volumio[7599]: info: ------------------------------------------- Jun 09 00:53:18 deck volumio[7599]: info: ----- Volumio3 ---- Jun 09 00:53:18 deck volumio[7599]: info: ------------------------------------------- Jun 09 00:53:18 deck volumio[7599]: info: ----- System startup ---- Jun 09 00:53:18 deck volumio[7599]: info: ------------------------------------------- Jun 09 00:53:18 deck wpa_supplicant[868]: wlan0: Trying to associate with SSID 'MainAP' Jun 09 00:53:19 deck dhcpcd[884]: wlan0: carrier acquired Jun 09 00:53:19 deck wpa_supplicant[868]: wlan0: Associated with 98:da:c4:dc:75:32 Jun 09 00:53:19 deck wpa_supplicant[868]: wlan0: CTRL-EVENT-CONNECTED - Connection to 98:da:c4:dc:75:32 completed [id=0 id_str=] Jun 09 00:53:19 deck wpa_supplicant[868]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 09 00:53:19 deck wpa_supplicant[868]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Jun 09 00:53:19 deck dhcpcd[884]: wlan0: IAID eb:c7:55:22 Jun 09 00:53:19 deck dhcpcd[884]: wlan0: carrier lost Jun 09 00:53:19 deck dhcpcd[884]: wlan0: carrier acquired Jun 09 00:53:19 deck dhcpcd[884]: wlan0: IAID eb:c7:55:22 Jun 09 00:53:19 deck dhcpcd[884]: wlan0: soliciting an IPv6 router Jun 09 00:53:19 deck volumio[7599]: info: MYVOLUMIO Environment detected Jun 09 00:53:19 deck volumio[7599]: info: Plugin folders cleanup Jun 09 00:53:19 deck volumio[7599]: info: Scanning into folder /volumio/app/plugins/ Jun 09 00:53:19 deck volumio[7599]: info: Scanning category audio_interface Jun 09 00:53:19 deck volumio[7599]: info: Scanning category miscellanea Jun 09 00:53:19 deck volumio[7599]: info: Scanning category music_service Jun 09 00:53:19 deck volumio[7599]: info: Scanning category plugins.json Jun 09 00:53:19 deck volumio[7599]: info: Scanning category system_controller Jun 09 00:53:19 deck volumio[7599]: info: Scanning category user_interface Jun 09 00:53:19 deck volumio[7599]: info: Scanning into folder /data/plugins/ Jun 09 00:53:19 deck volumio[7599]: info: Scanning category audio_interface Jun 09 00:53:19 deck volumio[7599]: info: Scanning category music_service Jun 09 00:53:19 deck volumio[7599]: info: Plugin folders cleanup completed Jun 09 00:53:19 deck volumio[7599]: info: ------------------------------------------- Jun 09 00:53:19 deck volumio[7599]: info: ----- Core plugins startup ---- Jun 09 00:53:19 deck volumio[7599]: info: ------------------------------------------- Jun 09 00:53:19 deck volumio[7599]: info: Loading plugins from folder /volumio/app/plugins/ Jun 09 00:53:19 deck volumio[7599]: info: Adding plugin upnp to MyMusic Plugins Jun 09 00:53:19 deck volumio[7599]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 09 00:53:19 deck volumio[7599]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 09 00:53:19 deck volumio[7599]: info: Loading plugins from folder /data/plugins/ Jun 09 00:53:19 deck volumio[7599]: info: Loading plugin "system"... Jun 09 00:53:19 deck volumio[7599]: info: Loading plugin "appearance"... Jun 09 00:53:19 deck dhcpcd[884]: wlan0: rebinding lease of 10.0.1.15 Jun 09 00:53:20 deck volumio[7599]: info: Loading plugin "network"... Jun 09 00:53:20 deck volumio[7599]: info: Refreshing Cached IP Addresses Jun 09 00:53:20 deck sudo[7641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 09 00:53:20 deck sudo[7641]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:20 deck sudo[7641]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:20 deck sudo[7643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 09 00:53:20 deck sudo[7643]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:20 deck sudo[7643]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:20 deck volumio[7599]: info: Loading plugin "services"... Jun 09 00:53:20 deck volumio[7599]: info: Loading plugin "alsa_controller"... Jun 09 00:53:20 deck sudo[7652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 09 00:53:20 deck sudo[7652]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:20 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 00:53:20 deck volumio[7599]: info: Loading plugin "wizard"... Jun 09 00:53:20 deck volumio[7599]: info: Loading plugin "networkfs"... Jun 09 00:53:20 deck volumio[7599]: info: Cannot mount NAS Local at system boot, trial number 1 ,retrying in 5 seconds Jun 09 00:53:20 deck volumio[7599]: info: Starting Udev Watcher for removable devices Jun 09 00:53:20 deck volumio[7599]: info: Ignoring mount for partition: boot Jun 09 00:53:20 deck volumio[7599]: info: Ignoring mount for partition: volumio Jun 09 00:53:20 deck volumio[7599]: info: Ignoring mount for partition: volumio_data Jun 09 00:53:20 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 09 00:53:20 deck volumio[7599]: info: Loading plugin "volumio_command_line_client"... Jun 09 00:53:20 deck volumio[7599]: info: Loading plugin "upnp"... Jun 09 00:53:20 deck volumio[7599]: info: [1749430400816] Starting Upmpd Daemon Jun 09 00:53:20 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 09 00:53:20 deck volumio[7599]: info: Loading plugin "my_music"... Jun 09 00:53:20 deck volumio[7599]: info: Loading plugin "mpd"... Jun 09 00:53:21 deck volumio[7599]: info: Loading plugin "upnp_browser"... Jun 09 00:53:21 deck sudo[7652]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:21 deck volumio[7599]: info: Loading plugin "alarm-clock"... Jun 09 00:53:21 deck volumio[7599]: info: Loading plugin "airplay_emulation"... Jun 09 00:53:21 deck volumio[7599]: info: Starting Shairport Sync Jun 09 00:53:21 deck volumio[7599]: info: Loading plugin "last_100"... Jun 09 00:53:21 deck volumio[7599]: info: Loading plugin "webradio"... Jun 09 00:53:21 deck volumio[7599]: info: Loading plugin "i2s_dacs"... Jun 09 00:53:21 deck volumio[7599]: info: I2S DAC not set, start Auto-detection Jun 09 00:53:21 deck volumio[7599]: info: Loading plugin "volumiodiscovery"... Jun 09 00:53:21 deck volumio[7599]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 09 00:53:21 deck volumio[7599]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 09 00:53:21 deck volumio[7599]: *** WARNING *** For more information see Jun 09 00:53:21 deck node[7599]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 09 00:53:21 deck volumio[7599]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 09 00:53:21 deck volumio[7599]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 09 00:53:21 deck volumio[7599]: *** WARNING *** For more information see Jun 09 00:53:21 deck node[7599]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 09 00:53:21 deck node[7599]: *** WARNING *** For more information see Jun 09 00:53:21 deck node[7599]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 09 00:53:21 deck node[7599]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 09 00:53:21 deck node[7599]: *** WARNING *** For more information see Jun 09 00:53:21 deck volumio[7599]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 09 00:53:21 deck volumio[7599]: info: Discovery: Started advertising with name: Deck Jun 09 00:53:21 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 09 00:53:21 deck volumio[7599]: info: Loading plugin "soundcloud"... Jun 09 00:53:22 deck volumio[7599]: info: Loading plugin "spop"... Jun 09 00:53:23 deck volumio-remote-updater[577]: [2025-06-09 00:53:23] [connect] Successful connection Jun 09 00:53:23 deck volumio[7599]: info: Loading plugin "squeezelite_mc"... Jun 09 00:53:24 deck volumio[7599]: info: Loading plugin "outputs"... Jun 09 00:53:24 deck volumio[7599]: info: Loading plugin "albumart"... Jun 09 00:53:24 deck volumio[7599]: info: Plugin example_plugin is not enabled Jun 09 00:53:24 deck volumio[7599]: info: Loading plugin "inputs"... Jun 09 00:53:24 deck volumio[7599]: info: Loading plugin "updater_comm"... Jun 09 00:53:24 deck volumio[7599]: info: Plugin mpdemulation is not enabled Jun 09 00:53:24 deck volumio[7599]: info: Loading plugin "rest_api"... Jun 09 00:53:24 deck volumio[7599]: info: Loading plugin "websocket"... Jun 09 00:53:24 deck volumio[7599]: info: Starting Socket.io Server version 2.3.0 Jun 09 00:53:24 deck volumio[7599]: info: Loading plugin "fusiondsp"... Jun 09 00:53:24 deck volumio[7599]: Forking 3 albumart workers Jun 09 00:53:24 deck dhcpcd[884]: wlan0: probing for an IPv4LL address Jun 09 00:53:24 deck volumio[7599]: info: Applying required configuration parameters for plugin fusiondsp Jun 09 00:53:24 deck dhcpcd[884]: wlan0: DHCP lease expired Jun 09 00:53:24 deck dhcpcd[884]: wlan0: soliciting a DHCP lease Jun 09 00:53:25 deck volumio[7599]: info: Loading plugin "RoonBridge"... Jun 09 00:53:25 deck volumio[7599]: info: Applying required configuration parameters for plugin RoonBridge Jun 09 00:53:25 deck volumio[7599]: info: Loading plugin "motherearthradio"... Jun 09 00:53:25 deck volumio[7599]: info: Applying required configuration parameters for plugin motherearthradio Jun 09 00:53:25 deck volumio[7599]: info: [1749430405734] [MotherEarth] API delay: 5 Jun 09 00:53:25 deck volumio[7599]: Starting albumart workers Jun 09 00:53:25 deck volumio[7599]: info: Loading i18n strings for locale en Jun 09 00:53:25 deck volumio[7599]: Updating browse sources language Jun 09 00:53:25 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:25 deck volumio[7599]: Starting albumart workers Jun 09 00:53:25 deck volumio[7599]: Starting albumart workers Jun 09 00:53:25 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 00:53:25 deck volumio[7599]: info: CoreCommandRouter::initPlayerControls Jun 09 00:53:25 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:25 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:25 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:26 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:26 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:26 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:26 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:26 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:26 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 00:53:26 deck volumio[7599]: Express server listening on port 3000 Jun 09 00:53:26 deck volumio[7599]: [Metrics] WebUI: 8s 182.57ms Jun 09 00:53:26 deck volumio[7599]: info: CoreStateMachine::resetVolumioState Jun 09 00:53:26 deck volumio[7599]: info: CoreStateMachine::getcurrentVolume Jun 09 00:53:26 deck volumio[7599]: info: CoreCommandRouter::volumioRetrievevolume Jun 09 00:53:26 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:26 deck volumio[7599]: info: Cannot mount NAS Local at system boot, trial number 2 ,retrying in 5 seconds Jun 09 00:53:26 deck volumio[7599]: info: Volumio Network Manager: Network status updated: 0 Jun 09 00:53:26 deck volumio-remote-updater[577]: [2025-06-09 00:53:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749430403 101 Jun 09 00:53:26 deck volumio[7599]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jun 09 00:53:26 deck volumio[7599]: info: Reloading queue from file Jun 09 00:53:26 deck volumio[7599]: info: CoreStateMachine::setRepeat true single undefined Jun 09 00:53:26 deck volumio[7599]: info: CoreStateMachine::pushState Jun 09 00:53:26 deck volumio[7599]: info: CorePlayQueue::getTrack 0 Jun 09 00:53:26 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 00:53:26 deck volumio[7599]: info: CoreCommandRouter::volumioPushState Jun 09 00:53:26 deck volumio[7599]: info: CoreStateMachine::setRandom null Jun 09 00:53:26 deck volumio[7599]: info: CoreStateMachine::pushState Jun 09 00:53:26 deck volumio[7599]: info: CorePlayQueue::getTrack 0 Jun 09 00:53:26 deck volumio[7599]: info: CoreCommandRouter::volumioPushState Jun 09 00:53:26 deck volumio[7599]: info: Setting Device type: Raspberry PI Jun 09 00:53:26 deck volumio[7599]: info: VolumeController:: Volume=70 Mute =false Jun 09 00:53:26 deck volumio[7599]: info: CoreStateMachine::pushState Jun 09 00:53:26 deck volumio[7599]: info: CorePlayQueue::getTrack 0 Jun 09 00:53:26 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 00:53:26 deck volumio[7599]: info: CoreCommandRouter::volumioPushState Jun 09 00:53:26 deck volumio[7599]: info: CoreStateMachine::updateTrackBlock Jun 09 00:53:26 deck volumio[7599]: info: CorePlayQueue::getTrackBlock Jun 09 00:53:26 deck volumio[7599]: info: CoreCommandRouter::volumioRetrievevolume Jun 09 00:53:26 deck volumio[7599]: info: Completed loading Core Plugins Jun 09 00:53:26 deck volumio[7599]: info: Preparing to generate the ALSA configuration file Jun 09 00:53:26 deck volumio[7599]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 09 00:53:26 deck volumio[7599]: info: Reading ALSA contributions from plugins. Jun 09 00:53:26 deck volumio[7599]: info: Asound.conf file unchanged, so no further update is needed Jun 09 00:53:26 deck volumio[7599]: info: Output device has changed, restarting MPD Jun 09 00:53:26 deck sudo[7782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 09 00:53:26 deck sudo[7782]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:26 deck volumio[7599]: info: Output device has changed, restarting Shairport Sync Jun 09 00:53:26 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:26 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:26 deck sudo[7782]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:26 deck sudo[7784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 09 00:53:26 deck sudo[7784]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:26 deck systemd[1]: Stopping Music Player Daemon... Jun 09 00:53:26 deck volumio[7599]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 09 00:53:26 deck volumio[7599]: info: ___________ START PLUGINS ___________ Jun 09 00:53:26 deck systemd[1]: mpd.service: Succeeded. Jun 09 00:53:26 deck systemd[1]: Stopped Music Player Daemon. Jun 09 00:53:26 deck volumio[7599]: info: ControllerMpd::onStart: Initializing MPD Jun 09 00:53:26 deck volumio[7599]: info: Creating MPD Configuration file Jun 09 00:53:26 deck sudo[7792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 09 00:53:26 deck sudo[7792]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:26 deck systemd[1]: Starting Music Player Daemon... Jun 09 00:53:26 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 00:53:26 deck volumio[7599]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 00:53:26 deck volumio[7599]: info: [1749430406976] CoreMusicLibrary::Adding element Media Servers Jun 09 00:53:26 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:26 deck sudo[7792]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:26 deck sudo[7795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 09 00:53:26 deck sudo[7795]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:27 deck systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 09 00:53:27 deck systemd[1]: mpd.service: Succeeded. Jun 09 00:53:27 deck systemd[1]: Stopped Music Player Daemon. Jun 09 00:53:27 deck systemd[1]: Starting Music Player Daemon... Jun 09 00:53:27 deck volumio[7599]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:27 deck volumio[7599]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 00:53:27 deck volumio[7599]: info: [1749430407127] CoreMusicLibrary::Adding element Last_100 Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 00:53:27 deck volumio[7599]: info: [1749430407132] CoreMusicLibrary::Adding element Webradio Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 00:53:27 deck volumio[7599]: info: Initializing BBC Radios Jun 09 00:53:27 deck sudo[7800]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 09 00:53:27 deck sudo[7800]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:27 deck sudo[7800]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 00:53:27 deck volumio[7599]: info: [1749430407238] CoreMusicLibrary::Adding element SoundCloud Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:27 deck volumio[7599]: Cannot find translation for source SoundCloud Jun 09 00:53:27 deck volumio[7599]: info: Checking for old spotify connect plugin installed Jun 09 00:53:27 deck volumio[7599]: info: [squeezelite_mc] Starting proxy server... Jun 09 00:53:27 deck volumio[7599]: info: Loading i18n strings for locale en Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:27 deck volumio[7599]: info: FusionDsp - mixtype--------------------- Hardware Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 00:53:27 deck volumio[7599]: info: [1749430407330] CoreMusicLibrary::Adding element Mother Earth Radio Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:27 deck volumio[7599]: Cannot find translation for source SoundCloud Jun 09 00:53:27 deck volumio[7599]: Cannot find translation for source Mother Earth Radio Jun 09 00:53:27 deck volumio[7599]: info: Volumio Calling Home Jun 09 00:53:27 deck sudo[7817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jun 09 00:53:27 deck sudo[7817]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:27 deck sudo[7817]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 09 00:53:27 deck volumio[7599]: [SpotifyConnect] Creating VLS config file Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:27 deck volumio[7599]: [SpotifyConnect] Starting metadata listener Jun 09 00:53:27 deck volumio[7599]: info: [squeezelite_mc] Proxy server started on port 34841 Jun 09 00:53:27 deck volumio[7599]: info: Preparing to generate the ALSA configuration file Jun 09 00:53:27 deck sudo[7823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jun 09 00:53:27 deck sudo[7823]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:27 deck sudo[7823]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::volumioRetrievevolume Jun 09 00:53:27 deck volumio[7599]: Unhandled rejection Error: No sockets available, cannot start. Jun 09 00:53:27 deck volumio[7599]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jun 09 00:53:27 deck volumio[7599]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jun 09 00:53:27 deck volumio[7599]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jun 09 00:53:27 deck volumio[7599]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jun 09 00:53:27 deck volumio[7599]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jun 09 00:53:27 deck volumio[7599]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jun 09 00:53:27 deck volumio[7599]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jun 09 00:53:27 deck volumio[7599]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jun 09 00:53:27 deck volumio[7599]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jun 09 00:53:27 deck volumio[7599]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jun 09 00:53:27 deck volumio[7599]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jun 09 00:53:27 deck volumio[7599]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 09 00:53:27 deck volumio[7599]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 09 00:53:27 deck volumio[7599]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jun 09 00:53:27 deck volumio[7599]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jun 09 00:53:27 deck volumio[7599]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 09 00:53:27 deck volumio[7599]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 09 00:53:27 deck volumio[7599]: info: Reading ALSA contributions from plugins. Jun 09 00:53:27 deck volumio[7599]: [SpotifyConnect] Vollibrespot Daemon service started! Jun 09 00:53:27 deck volumio[7599]: [Metrics] SpotifyConnect: 0s 295.57ms Jun 09 00:53:27 deck volumio[7599]: info: MPD Permissions set Jun 09 00:53:27 deck volumio[7599]: info: MPD Permissions set Jun 09 00:53:27 deck volumio[7599]: info: VolumeController:: Volume=70 Mute =false Jun 09 00:53:27 deck volumio[7599]: info: CoreStateMachine::pushState Jun 09 00:53:27 deck volumio[7599]: info: CorePlayQueue::getTrack 0 Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::volumioPushState Jun 09 00:53:27 deck volumio[7599]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 09 00:53:27 deck volumio[7599]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 09 00:53:27 deck volumio[7599]: info: VolumeController:: Volume=70 Mute =false Jun 09 00:53:27 deck volumio[7599]: info: CoreStateMachine::pushState Jun 09 00:53:27 deck volumio[7599]: info: CorePlayQueue::getTrack 0 Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::volumioPushState Jun 09 00:53:27 deck volumio[7599]: info: [squeezelite_mc] Server discovery started Jun 09 00:53:27 deck volumio[7599]: info: [squeezelite_mc] Player finder started Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:27 deck volumio[7599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:27 deck volumio[7599]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 09 00:53:27 deck volumio[7599]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 00:53:27 deck volumio[7599]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 09 00:53:27 deck volumio[7599]: at doSend (dgram.js:692:16) Jun 09 00:53:27 deck volumio[7599]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 09 00:53:27 deck volumio[7599]: at afterDns (dgram.js:638:5) Jun 09 00:53:27 deck volumio[7599]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 09 00:53:27 deck volumio[7599]: errno: -101, Jun 09 00:53:27 deck volumio[7599]: code: 'ENETUNREACH', Jun 09 00:53:27 deck volumio[7599]: syscall: 'send', Jun 09 00:53:27 deck volumio[7599]: address: '255.255.255.255', Jun 09 00:53:27 deck volumio[7599]: port: 3483 Jun 09 00:53:27 deck volumio[7599]: } Jun 09 00:53:27 deck volumio[7599]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 00:53:28 deck sudo[7838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-09 00:52 Jun 09 00:53:28 deck sudo[7838]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:28 deck sudo[7838]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:28 deck volumio-remote-updater[577]: [2025-06-09 00:53:28] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 09 00:53:28 deck systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 09 00:53:28 deck volumio-remote-updater[577]: [2025-06-09 00:53:28] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 09 00:53:28 deck sudo[7795]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:28 deck sudo[7784]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:28 deck systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 09 00:53:28 deck mpd[7808]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 09 00:53:28 deck mpd[7808]: output: No 'audio_output' defined in config file Jun 09 00:53:28 deck systemd[1]: Started dynamicswap service. Jun 09 00:53:28 deck mpd[7808]: output: Successfully detected a sndio audio device Jun 09 00:53:28 deck mpd[7808]: zeroconf: No global port, disabling zeroconf Jun 09 00:53:28 deck systemd[1]: Started Music Player Daemon. Jun 09 00:53:28 deck systemd[1]: dynamicswap.service: Succeeded. Jun 09 00:53:29 deck systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 09 00:53:29 deck systemd[1]: volumio.service: Scheduled restart job, restart counter is at 19. Jun 09 00:53:29 deck systemd[1]: Stopped Volumio Backend Module. Jun 09 00:53:29 deck systemd[1]: Started Volumio Backend Module. Jun 09 00:53:29 deck systemd[1]: Started dynamicswap service. Jun 09 00:53:29 deck systemd[1]: dynamicswap.service: Succeeded. Jun 09 00:53:30 deck dhcpcd[884]: wlan0: using IPv4LL address 169.254.206.194 Jun 09 00:53:30 deck avahi-daemon[574]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.206.194. Jun 09 00:53:30 deck dhcpcd[884]: wlan0: adding route to 169.254.0.0/16 Jun 09 00:53:30 deck dhcpcd[884]: wlan0: adding default route Jun 09 00:53:30 deck avahi-daemon[574]: New relevant interface wlan0.IPv4 for mDNS. Jun 09 00:53:30 deck avahi-daemon[574]: Registering new address record for 169.254.206.194 on wlan0.IPv4. Jun 09 00:53:31 deck volumio[7853]: info: ------------------------------------------- Jun 09 00:53:31 deck volumio[7853]: info: ----- Volumio3 ---- Jun 09 00:53:31 deck volumio[7853]: info: ------------------------------------------- Jun 09 00:53:31 deck volumio[7853]: info: ----- System startup ---- Jun 09 00:53:31 deck volumio[7853]: info: ------------------------------------------- Jun 09 00:53:31 deck ntpd[759]: Listen normally on 11 wlan0 169.254.206.194:123 Jun 09 00:53:31 deck ntpd[759]: new interface(s) found: waking up resolver Jun 09 00:53:31 deck volumio[7853]: info: MYVOLUMIO Environment detected Jun 09 00:53:31 deck volumio[7853]: info: Plugin folders cleanup Jun 09 00:53:31 deck volumio[7853]: info: Scanning into folder /volumio/app/plugins/ Jun 09 00:53:31 deck volumio[7853]: info: Scanning category audio_interface Jun 09 00:53:31 deck volumio[7853]: info: Scanning category miscellanea Jun 09 00:53:31 deck volumio[7853]: info: Scanning category music_service Jun 09 00:53:31 deck volumio[7853]: info: Scanning category plugins.json Jun 09 00:53:31 deck volumio[7853]: info: Scanning category system_controller Jun 09 00:53:31 deck volumio[7853]: info: Scanning category user_interface Jun 09 00:53:31 deck volumio[7853]: info: Scanning into folder /data/plugins/ Jun 09 00:53:31 deck volumio[7853]: info: Scanning category audio_interface Jun 09 00:53:31 deck volumio[7853]: info: Scanning category music_service Jun 09 00:53:31 deck volumio[7853]: info: Plugin folders cleanup completed Jun 09 00:53:31 deck volumio[7853]: info: ------------------------------------------- Jun 09 00:53:31 deck volumio[7853]: info: ----- Core plugins startup ---- Jun 09 00:53:31 deck volumio[7853]: info: ------------------------------------------- Jun 09 00:53:31 deck volumio[7853]: info: Loading plugins from folder /volumio/app/plugins/ Jun 09 00:53:31 deck volumio[7853]: info: Adding plugin upnp to MyMusic Plugins Jun 09 00:53:31 deck volumio[7853]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 09 00:53:31 deck volumio[7853]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 09 00:53:31 deck volumio[7853]: info: Loading plugins from folder /data/plugins/ Jun 09 00:53:31 deck volumio[7853]: info: Loading plugin "system"... Jun 09 00:53:31 deck volumio[7853]: info: Loading plugin "appearance"... Jun 09 00:53:32 deck volumio[7853]: info: Loading plugin "network"... Jun 09 00:53:32 deck volumio[7853]: info: Refreshing Cached IP Addresses Jun 09 00:53:33 deck sudo[7897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 09 00:53:33 deck sudo[7897]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:33 deck sudo[7897]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:33 deck sudo[7899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 09 00:53:33 deck sudo[7899]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:33 deck sudo[7899]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:33 deck volumio[7853]: info: Loading plugin "services"... Jun 09 00:53:33 deck volumio[7853]: info: Loading plugin "alsa_controller"... Jun 09 00:53:33 deck sudo[7908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 09 00:53:33 deck sudo[7908]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:33 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 00:53:33 deck volumio[7853]: info: Loading plugin "wizard"... Jun 09 00:53:33 deck volumio[7853]: info: Loading plugin "networkfs"... Jun 09 00:53:33 deck volumio[7853]: info: Cannot mount NAS Local at system boot, trial number 1 ,retrying in 5 seconds Jun 09 00:53:33 deck volumio[7853]: info: Starting Udev Watcher for removable devices Jun 09 00:53:33 deck volumio[7853]: info: Ignoring mount for partition: boot Jun 09 00:53:33 deck volumio[7853]: info: Ignoring mount for partition: volumio Jun 09 00:53:33 deck volumio[7853]: info: Ignoring mount for partition: volumio_data Jun 09 00:53:33 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 09 00:53:33 deck volumio[7853]: info: Loading plugin "volumio_command_line_client"... Jun 09 00:53:33 deck volumio[7853]: info: Loading plugin "upnp"... Jun 09 00:53:33 deck volumio[7853]: info: [1749430413278] Starting Upmpd Daemon Jun 09 00:53:33 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 09 00:53:33 deck volumio[7853]: info: Loading plugin "my_music"... Jun 09 00:53:33 deck volumio[7853]: info: Loading plugin "mpd"... Jun 09 00:53:33 deck volumio[7853]: info: Loading plugin "upnp_browser"... Jun 09 00:53:33 deck sudo[7908]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:33 deck volumio-remote-updater[577]: [2025-06-09 00:53:33] [connect] Successful connection Jun 09 00:53:34 deck volumio[7853]: info: Loading plugin "alarm-clock"... Jun 09 00:53:34 deck volumio[7853]: info: Loading plugin "airplay_emulation"... Jun 09 00:53:34 deck volumio[7853]: info: Starting Shairport Sync Jun 09 00:53:34 deck volumio[7853]: info: Loading plugin "last_100"... Jun 09 00:53:34 deck volumio[7853]: info: Loading plugin "webradio"... Jun 09 00:53:34 deck volumio[7853]: info: Loading plugin "i2s_dacs"... Jun 09 00:53:34 deck volumio[7853]: info: I2S DAC not set, start Auto-detection Jun 09 00:53:34 deck volumio[7853]: info: Loading plugin "volumiodiscovery"... Jun 09 00:53:34 deck volumio[7853]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 09 00:53:34 deck volumio[7853]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 09 00:53:34 deck volumio[7853]: *** WARNING *** For more information see Jun 09 00:53:34 deck node[7853]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 09 00:53:34 deck volumio[7853]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 09 00:53:34 deck volumio[7853]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 09 00:53:34 deck volumio[7853]: *** WARNING *** For more information see Jun 09 00:53:34 deck node[7853]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 09 00:53:34 deck node[7853]: *** WARNING *** For more information see Jun 09 00:53:34 deck node[7853]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 09 00:53:34 deck node[7853]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 09 00:53:34 deck node[7853]: *** WARNING *** For more information see Jun 09 00:53:34 deck volumio[7853]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 09 00:53:34 deck volumio[7853]: info: Discovery: Started advertising with name: Deck Jun 09 00:53:34 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 09 00:53:34 deck volumio[7853]: info: Loading plugin "soundcloud"... Jun 09 00:53:34 deck volumio[7853]: info: Loading plugin "spop"... Jun 09 00:53:35 deck volumio[7853]: info: Loading plugin "squeezelite_mc"... Jun 09 00:53:36 deck volumio[7853]: info: Loading plugin "outputs"... Jun 09 00:53:36 deck volumio[7853]: info: Loading plugin "albumart"... Jun 09 00:53:36 deck volumio[7853]: info: Plugin example_plugin is not enabled Jun 09 00:53:36 deck volumio[7853]: info: Loading plugin "inputs"... Jun 09 00:53:36 deck volumio[7853]: info: Loading plugin "updater_comm"... Jun 09 00:53:36 deck volumio[7853]: info: Plugin mpdemulation is not enabled Jun 09 00:53:36 deck volumio[7853]: info: Loading plugin "rest_api"... Jun 09 00:53:36 deck volumio[7853]: info: Loading plugin "websocket"... Jun 09 00:53:36 deck volumio[7853]: info: Starting Socket.io Server version 2.3.0 Jun 09 00:53:36 deck volumio[7853]: info: Loading plugin "fusiondsp"... Jun 09 00:53:37 deck volumio[7853]: Forking 3 albumart workers Jun 09 00:53:37 deck volumio[7853]: info: Applying required configuration parameters for plugin fusiondsp Jun 09 00:53:37 deck volumio[7853]: info: Loading plugin "RoonBridge"... Jun 09 00:53:37 deck volumio[7853]: info: Applying required configuration parameters for plugin RoonBridge Jun 09 00:53:37 deck volumio[7853]: info: Loading plugin "motherearthradio"... Jun 09 00:53:38 deck volumio[7853]: Starting albumart workers Jun 09 00:53:38 deck volumio[7853]: info: Applying required configuration parameters for plugin motherearthradio Jun 09 00:53:38 deck volumio[7853]: info: [1749430418185] [MotherEarth] API delay: 5 Jun 09 00:53:38 deck volumio[7853]: info: Loading i18n strings for locale en Jun 09 00:53:38 deck volumio[7853]: Updating browse sources language Jun 09 00:53:38 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:38 deck volumio[7853]: Starting albumart workers Jun 09 00:53:38 deck volumio[7853]: Starting albumart workers Jun 09 00:53:38 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 00:53:38 deck volumio[7853]: info: CoreCommandRouter::initPlayerControls Jun 09 00:53:38 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:38 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:38 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:38 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:38 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:38 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:38 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:38 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:38 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 00:53:38 deck volumio[7853]: Express server listening on port 3000 Jun 09 00:53:38 deck volumio[7853]: [Metrics] WebUI: 8s 184.53ms Jun 09 00:53:38 deck volumio[7853]: info: CoreStateMachine::resetVolumioState Jun 09 00:53:38 deck volumio[7853]: info: CoreStateMachine::getcurrentVolume Jun 09 00:53:38 deck volumio[7853]: info: CoreCommandRouter::volumioRetrievevolume Jun 09 00:53:38 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:38 deck volumio[7853]: info: Cannot mount NAS Local at system boot, trial number 2 ,retrying in 5 seconds Jun 09 00:53:38 deck volumio[7853]: info: Volumio Network Manager: Network status updated: 2 Jun 09 00:53:38 deck volumio-remote-updater[577]: [2025-06-09 00:53:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749430413 101 Jun 09 00:53:38 deck volumio[7853]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jun 09 00:53:39 deck volumio[7853]: info: Reloading queue from file Jun 09 00:53:39 deck volumio[7853]: info: CoreStateMachine::setRepeat true single undefined Jun 09 00:53:39 deck volumio[7853]: info: CoreStateMachine::pushState Jun 09 00:53:39 deck volumio[7853]: info: CorePlayQueue::getTrack 0 Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::volumioPushState Jun 09 00:53:39 deck volumio[7853]: info: CoreStateMachine::setRandom null Jun 09 00:53:39 deck volumio[7853]: info: CoreStateMachine::pushState Jun 09 00:53:39 deck volumio[7853]: info: CorePlayQueue::getTrack 0 Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::volumioPushState Jun 09 00:53:39 deck volumio[7853]: info: Setting Device type: Raspberry PI Jun 09 00:53:39 deck volumio[7853]: info: VolumeController:: Volume=70 Mute =false Jun 09 00:53:39 deck volumio[7853]: info: CoreStateMachine::pushState Jun 09 00:53:39 deck volumio[7853]: info: CorePlayQueue::getTrack 0 Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::volumioPushState Jun 09 00:53:39 deck volumio[7853]: info: CoreStateMachine::updateTrackBlock Jun 09 00:53:39 deck volumio[7853]: info: CorePlayQueue::getTrackBlock Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::volumioRetrievevolume Jun 09 00:53:39 deck volumio[7853]: info: Completed loading Core Plugins Jun 09 00:53:39 deck volumio[7853]: info: Preparing to generate the ALSA configuration file Jun 09 00:53:39 deck volumio[7853]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 09 00:53:39 deck volumio[7853]: info: Reading ALSA contributions from plugins. Jun 09 00:53:39 deck volumio[7853]: info: Asound.conf file unchanged, so no further update is needed Jun 09 00:53:39 deck volumio[7853]: info: Output device has changed, restarting MPD Jun 09 00:53:39 deck sudo[8005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 09 00:53:39 deck sudo[8005]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:39 deck volumio[7853]: info: Output device has changed, restarting Shairport Sync Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:39 deck sudo[8005]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:39 deck sudo[8007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 09 00:53:39 deck sudo[8007]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:39 deck systemd[1]: Stopping Music Player Daemon... Jun 09 00:53:39 deck volumio[7853]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 09 00:53:39 deck volumio[7853]: info: ___________ START PLUGINS ___________ Jun 09 00:53:39 deck systemd[1]: mpd.service: Succeeded. Jun 09 00:53:39 deck systemd[1]: Stopped Music Player Daemon. Jun 09 00:53:39 deck volumio[7853]: info: ControllerMpd::onStart: Initializing MPD Jun 09 00:53:39 deck volumio[7853]: info: Creating MPD Configuration file Jun 09 00:53:39 deck sudo[8015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 09 00:53:39 deck sudo[8015]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 00:53:39 deck volumio[7853]: info: [1749430419425] CoreMusicLibrary::Adding element Media Servers Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:39 deck systemd[1]: Starting Music Player Daemon... Jun 09 00:53:39 deck sudo[8015]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:39 deck sudo[8018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 09 00:53:39 deck sudo[8018]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:39 deck systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 09 00:53:39 deck systemd[1]: mpd.service: Succeeded. Jun 09 00:53:39 deck systemd[1]: Stopped Music Player Daemon. Jun 09 00:53:39 deck systemd[1]: Starting Music Player Daemon... Jun 09 00:53:39 deck volumio[7853]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:39 deck volumio[7853]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 00:53:39 deck volumio[7853]: info: [1749430419585] CoreMusicLibrary::Adding element Last_100 Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 00:53:39 deck volumio[7853]: info: [1749430419590] CoreMusicLibrary::Adding element Webradio Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 00:53:39 deck volumio[7853]: info: Initializing BBC Radios Jun 09 00:53:39 deck sudo[8021]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 09 00:53:39 deck sudo[8021]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:39 deck sudo[8021]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 00:53:39 deck volumio[7853]: info: [1749430419695] CoreMusicLibrary::Adding element SoundCloud Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:39 deck volumio[7853]: Cannot find translation for source SoundCloud Jun 09 00:53:39 deck volumio[7853]: info: Checking for old spotify connect plugin installed Jun 09 00:53:39 deck volumio[7853]: info: [squeezelite_mc] Starting proxy server... Jun 09 00:53:39 deck volumio[7853]: info: Loading i18n strings for locale en Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:39 deck volumio[7853]: info: FusionDsp - mixtype--------------------- Hardware Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 00:53:39 deck volumio[7853]: info: [1749430419782] CoreMusicLibrary::Adding element Mother Earth Radio Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 00:53:39 deck volumio[7853]: Cannot find translation for source SoundCloud Jun 09 00:53:39 deck volumio[7853]: Cannot find translation for source Mother Earth Radio Jun 09 00:53:39 deck volumio[7853]: info: Volumio Calling Home Jun 09 00:53:39 deck sudo[8042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jun 09 00:53:39 deck sudo[8042]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:39 deck sudo[8042]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 09 00:53:39 deck volumio[7853]: [SpotifyConnect] Creating VLS config file Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:39 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:39 deck volumio[7853]: [SpotifyConnect] Starting metadata listener Jun 09 00:53:39 deck volumio[7853]: info: [squeezelite_mc] Proxy server started on port 41617 Jun 09 00:53:40 deck volumio[7853]: info: Preparing to generate the ALSA configuration file Jun 09 00:53:40 deck sudo[8048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jun 09 00:53:40 deck sudo[8048]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:40 deck sudo[8048]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::volumioRetrievevolume Jun 09 00:53:40 deck volumio[7853]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 09 00:53:40 deck volumio[7853]: info: Reading ALSA contributions from plugins. Jun 09 00:53:40 deck volumio[7853]: [SpotifyConnect] Vollibrespot Daemon service started! Jun 09 00:53:40 deck volumio[7853]: [Metrics] SpotifyConnect: 0s 235.44ms Jun 09 00:53:40 deck volumio[7853]: info: MPD Permissions set Jun 09 00:53:40 deck volumio[7853]: info: MPD Permissions set Jun 09 00:53:40 deck volumio[7853]: info: VolumeController:: Volume=70 Mute =false Jun 09 00:53:40 deck volumio[7853]: info: CoreStateMachine::pushState Jun 09 00:53:40 deck volumio[7853]: info: CorePlayQueue::getTrack 0 Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::volumioPushState Jun 09 00:53:40 deck volumio[7853]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 09 00:53:40 deck volumio[7853]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: VolumeController:: Volume=70 Mute =false Jun 09 00:53:40 deck volumio[7853]: info: CoreStateMachine::pushState Jun 09 00:53:40 deck volumio[7853]: info: CorePlayQueue::getTrack 0 Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::volumioPushState Jun 09 00:53:40 deck volumio[7853]: info: [squeezelite_mc] Server discovery started Jun 09 00:53:40 deck volumio[7853]: info: [squeezelite_mc] Player finder started Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 09 00:53:40 deck volumio[7853]: info: Starting Shairport Sync Jun 09 00:53:40 deck volumio[7853]: info: Starting Shairport Sync Jun 09 00:53:40 deck sudo[8063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 09 00:53:40 deck volumio[7853]: info: Starting Shairport Sync Jun 09 00:53:40 deck sudo[8063]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:40 deck sudo[8065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 09 00:53:40 deck sudo[8065]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:40 deck volumio[7853]: info: [squeezelite_mc] Card 0 supports the following ALSA formats: ["U8","S16_LE"] Jun 09 00:53:40 deck volumio[7853]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"deck","card":"0","mixerType":"Hardware","mixer":"PCM","dsdFormat":null} Jun 09 00:53:40 deck systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 09 00:53:40 deck systemd[1]: shairport-sync.service: Succeeded. Jun 09 00:53:40 deck volumio[7853]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 09 00:53:40 deck systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 09 00:53:40 deck sudo[8069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 09 00:53:40 deck sudo[8069]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:40 deck systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 09 00:53:40 deck volumio[7853]: info: Asound.conf file unchanged, so no further update is needed Jun 09 00:53:40 deck volumio[7853]: info: Output device has changed, restarting MPD Jun 09 00:53:40 deck sudo[8063]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:40 deck sudo[8065]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:40 deck sudo[8076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 09 00:53:40 deck sudo[8076]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:40 deck sudo[8076]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:40 deck volumio[7853]: info: Output device has changed, restarting Shairport Sync Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:40 deck systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 09 00:53:40 deck systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 09 00:53:40 deck systemd[1]: shairport-sync.service: Succeeded. Jun 09 00:53:40 deck systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 09 00:53:40 deck systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 09 00:53:40 deck sudo[8079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 09 00:53:40 deck sudo[8079]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:40 deck sudo[8069]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:40 deck sudo[8074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 09 00:53:40 deck sudo[8074]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:40 deck sudo[8074]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:40 deck systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 09 00:53:40 deck systemd[1]: mpd.service: Succeeded. Jun 09 00:53:40 deck systemd[1]: Stopped Music Player Daemon. Jun 09 00:53:40 deck systemd[1]: Starting Music Player Daemon... Jun 09 00:53:40 deck volumio[7853]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 09 00:53:40 deck volumio[7853]: [SpotifyConnect] Creating VLS config file Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: [SpotifyConnect] Restarting Vollibrespot Daemon Jun 09 00:53:40 deck volumio[7853]: info: MPD Permissions set Jun 09 00:53:40 deck volumio[7853]: info: Shairport-Sync Started Jun 09 00:53:40 deck volumio[7853]: Error adding Membership: Error: addMembership EINVAL Jun 09 00:53:40 deck volumio[7853]: info: Shairport-Sync Started Jun 09 00:53:40 deck volumio[7853]: info: Shairport-Sync Started Jun 09 00:53:40 deck sudo[8090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Jun 09 00:53:40 deck sudo[8090]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:40 deck volumio[7853]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck systemd[1]: Stopping Volspotconnect2 Daemon... Jun 09 00:53:40 deck systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Jun 09 00:53:40 deck systemd[1]: volspotconnect.service: Succeeded. Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:40 deck systemd[1]: Stopped Volspotconnect2 Daemon. Jun 09 00:53:40 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:41 deck sudo[8087]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 09 00:53:41 deck sudo[8087]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:41 deck systemd[1]: Started Volspotconnect2 Daemon. Jun 09 00:53:41 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:53:41 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:41 deck volumio[7853]: info: Starting Shairport Sync Jun 09 00:53:41 deck sudo[8087]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:41 deck sudo[8102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 09 00:53:41 deck sudo[8102]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:41 deck sudo[8090]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:41 deck volumio[8095]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Jun 09 00:53:41 deck volumio[8095]: Reading Config from "/tmp/volspotify.toml" Jun 09 00:53:41 deck volumio[8095]: [Vollibrespot] : Using Alsa backend with device: volumio Jun 09 00:53:41 deck volumio[7853]: [SpotifyConnect] Vollibrespot Daemon service restarted! Jun 09 00:53:41 deck sudo[8094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Jun 09 00:53:41 deck sudo[8094]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:41 deck sudo[8094]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:41 deck systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 09 00:53:41 deck volumio[7853]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Jun 09 00:53:41 deck systemd[1]: shairport-sync.service: Succeeded. Jun 09 00:53:41 deck systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 09 00:53:41 deck volumio[8095]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Jun 09 00:53:41 deck systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 09 00:53:41 deck sudo[8102]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:41 deck sudo[8109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 09 00:53:41 deck sudo[8109]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:41 deck volumio[7853]: info: Shairport-Sync Started Jun 09 00:53:41 deck systemd[1]: Reloading. Jun 09 00:53:41 deck systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 09 00:53:41 deck volumio[7853]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 09 00:53:41 deck volumio[7853]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/sr.log && /bin/chmod 666 /tmp/sr.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jun 09 00:53:41 deck volumio[7853]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 09 00:53:41 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:41 deck volumio[7853]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Jun 09 00:53:41 deck volumio[7853]: info: FusionDsp loaded Jun 09 00:53:41 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 09 00:53:41 deck sudo[8136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 09 00:53:41 deck sudo[8136]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:41 deck volumio[7853]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 09 00:53:41 deck volumio[7853]: info: Adding Signal Path Element [object Object] Jun 09 00:53:41 deck volumio[7853]: info: Adding fusiondspeq DSP Signal Path Element Jun 09 00:53:41 deck volumio[7853]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 09 00:53:41 deck volumio[7853]: info: FusionDsp - Effects disabled Jun 09 00:53:42 deck sudo[8109]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:42 deck volumio[7853]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 09 00:53:42 deck sudo[8136]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:42 deck sudo[8139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 09 00:53:42 deck sudo[8139]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:42 deck sudo[8139]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:42 deck volumio[7853]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Jun 09 00:53:42 deck sudo[8142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Jun 09 00:53:42 deck sudo[8142]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:42 deck systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Jun 09 00:53:42 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:42 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:53:42 deck systemd[1]: squeezelite.service: Succeeded. Jun 09 00:53:42 deck systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Jun 09 00:53:42 deck sudo[8142]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:42 deck mpd[8103]: Jun 09 00:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 09 00:53:42 deck systemd[1]: Started Music Player Daemon. Jun 09 00:53:42 deck sudo[8007]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:42 deck sudo[8018]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:42 deck sudo[8079]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:42 deck volumio[7853]: error: MPD error: The expression evaluated to a falsy value: Jun 09 00:53:42 deck volumio[7853]: assert.ok(self.idling) Jun 09 00:53:42 deck volumio[7853]: error: The expression evaluated to a falsy value: Jun 09 00:53:42 deck volumio[7853]: assert.ok(self.idling) Jun 09 00:53:42 deck volumio[7853]: info: FusionDsp - Jun 09 00:53:42 deck volumio[7853]: error: MPD error: The expression evaluated to a falsy value: Jun 09 00:53:42 deck volumio[7853]: assert.ok(self.idling) Jun 09 00:53:42 deck volumio[7853]: error: The expression evaluated to a falsy value: Jun 09 00:53:42 deck volumio[7853]: assert.ok(self.idling) Jun 09 00:53:42 deck volumio[7853]: info: MPD running with PID8103 Jun 09 00:53:42 deck volumio[7853]: ,establishing connection Jun 09 00:53:42 deck volumio[7853]: error: updateQueue error: null Jun 09 00:53:42 deck volumio[7853]: error: updateQueue error: null Jun 09 00:53:43 deck volumio[7853]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 09 00:53:43 deck sudo[8150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 09 00:53:43 deck sudo[8150]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:43 deck sudo[8150]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:43 deck volumio[7853]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Jun 09 00:53:43 deck sudo[8168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Jun 09 00:53:43 deck sudo[8168]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:43 deck sudo[8168]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:43 deck volumio[7853]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Jun 09 00:53:43 deck sudo[8171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Jun 09 00:53:43 deck sudo[8171]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:43 deck sudo[8175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 09 00:53:43 deck sudo[8175]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:43 deck sudo[8175]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:43 deck systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Jun 09 00:53:43 deck sudo[8177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 09 00:53:43 deck systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Jun 09 00:53:43 deck sudo[8177]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:43 deck sudo[8171]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:43 deck sudo[8177]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:43 deck sudo[8182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 09 00:53:43 deck sudo[8182]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:43 deck sudo[8182]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:43 deck volumio[7853]: info: Upmpdcli Daemon Started Jun 09 00:53:43 deck volumio[7853]: info: Cannot mount NAS Local at system boot, trial number 3 ,retrying in 5 seconds Jun 09 00:53:43 deck volumio[7853]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 09 00:53:43 deck sudo[8187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 09 00:53:43 deck sudo[8187]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:43 deck sudo[8187]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:44 deck volumio[7853]: info: Checking Spotify Web API Jun 09 00:53:44 deck volumio[7853]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 09 00:53:44 deck sudo[8190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 09 00:53:44 deck sudo[8190]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:44 deck sudo[8190]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:45 deck volumio[7853]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 09 00:53:45 deck sudo[8193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 09 00:53:45 deck sudo[8193]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:45 deck sudo[8193]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:45 deck volumio[7853]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 09 00:53:45 deck sudo[8196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 09 00:53:45 deck sudo[8196]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:45 deck sudo[8196]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:46 deck volumio[7853]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 09 00:53:46 deck sudo[8199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 09 00:53:46 deck sudo[8199]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 00:53:46 deck sudo[8199]: pam_unix(sudo:session): session closed for user root Jun 09 00:53:46 deck volumio[7853]: info: Completed starting Core Plugins Jun 09 00:53:46 deck volumio[7853]: info: ------------------------------------------- Jun 09 00:53:46 deck volumio[7853]: info: ----- MyVolumio plugins startup ---- Jun 09 00:53:46 deck volumio[7853]: info: ------------------------------------------- Jun 09 00:53:46 deck volumio[7853]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 09 00:53:47 deck volumio[7853]: info: Checking Spotify Web API Jun 09 00:53:47 deck volumio[7853]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 09 00:53:48 deck volumio[7853]: info: Cannot mount NAS Local at system boot, trial number 4 ,retrying in 5 seconds Jun 09 00:53:53 deck volumio[7853]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jun 09 00:54:04 deck wpa_supplicant[868]: wlan0: CTRL-EVENT-DISCONNECTED bssid=98:da:c4:dc:75:32 reason=1 Jun 09 00:54:04 deck dhcpcd[884]: wlan0: carrier lost Jun 09 00:54:04 deck wpa_supplicant[868]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 09 00:54:04 deck wpa_supplicant[868]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US Jun 09 00:54:04 deck avahi-daemon[574]: Withdrawing address record for 169.254.206.194 on wlan0. Jun 09 00:54:04 deck avahi-daemon[574]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.206.194. Jun 09 00:54:04 deck dhcpcd[884]: wlan0: deleting route to 169.254.0.0/16 Jun 09 00:54:04 deck dhcpcd[884]: wlan0: deleting default route Jun 09 00:54:04 deck avahi-daemon[574]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 09 00:54:04 deck volumio[7853]: info: Discovery: A device disappeared from network Jun 09 00:54:04 deck volumio[7853]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Jun 09 00:54:04 deck wpa_supplicant[868]: wlan0: Trying to associate with SSID 'MainAP' Jun 09 00:54:05 deck dhcpcd[884]: wlan0: carrier acquired Jun 09 00:54:05 deck wpa_supplicant[868]: wlan0: Associated with 98:da:c4:dc:4e:6e Jun 09 00:54:05 deck wpa_supplicant[868]: wlan0: CTRL-EVENT-CONNECTED - Connection to 98:da:c4:dc:4e:6e completed [id=0 id_str=] Jun 09 00:54:05 deck wpa_supplicant[868]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 09 00:54:05 deck wpa_supplicant[868]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Jun 09 00:54:05 deck dhcpcd[884]: wlan0: IAID eb:c7:55:22 Jun 09 00:54:05 deck dhcpcd[884]: wlan0: carrier lost Jun 09 00:54:05 deck volumio[7853]: error: FusionDsp - WebSocket error: [object Object] Jun 09 00:54:05 deck dhcpcd[884]: wlan0: carrier acquired Jun 09 00:54:05 deck dhcpcd[884]: wlan0: IAID eb:c7:55:22 Jun 09 00:54:05 deck volumio[7853]: info: CoreCommandRouter::volumioGetState Jun 09 00:54:05 deck volumio[7853]: info: CorePlayQueue::getTrack 0 Jun 09 00:54:05 deck volumio[7853]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 09 00:54:05 deck volumio[7853]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 09 00:54:05 deck dhcpcd[884]: wlan0: soliciting a DHCP lease Jun 09 00:54:05 deck dhcpcd[884]: wlan0: offered 10.0.1.15 from 10.0.0.138 Jun 09 00:54:05 deck dhcpcd[884]: wlan0: probing address 10.0.1.15/16 Jun 09 00:54:05 deck ntpd[759]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 09 00:54:05 deck ntpd[759]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 09 00:54:06 deck dhcpcd[884]: wlan0: soliciting an IPv6 router Jun 09 00:54:06 deck volumio[7853]: error: MyVolumio Plugin failed to start in a timely fashion Jun 09 00:54:06 deck volumio[7853]: [Metrics] CommandRouter: 35s 283.44ms Jun 09 00:54:06 deck volumio[7853]: info: CoreCommandRouter::volumiosetStartupVolume Jun 09 00:54:06 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 00:54:06 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:54:06 deck volumio[7853]: info: CoreCommandRouter::Close All Modals sent Jun 09 00:54:06 deck volumio[7853]: info: CoreCommandRouter::Close All Modals sent Jun 09 00:54:06 deck ntpd[759]: Deleting interface #11 wlan0, 169.254.206.194#123, interface stats: received=0, sent=6, dropped=0, active_time=35 secs Jun 09 00:54:06 deck ntpd[759]: 194.0.5.123 local addr 169.254.206.194 -> Jun 09 00:54:06 deck ntpd[759]: 51.16.235.6 local addr 169.254.206.194 -> Jun 09 00:54:06 deck ntpd[759]: 51.16.77.36 local addr 169.254.206.194 -> Jun 09 00:54:06 deck ntpd[759]: 64.176.168.216 local addr 169.254.206.194 -> Jun 09 00:54:06 deck ntpd[759]: 129.159.140.221 local addr 169.254.206.194 -> Jun 09 00:54:06 deck ntpd[759]: 51.17.20.53 local addr 169.254.206.194 -> Jun 09 00:54:07 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 09 00:54:07 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 09 00:54:07 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 09 00:54:09 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 00:54:09 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 00:54:09 deck volumio[7853]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 09 00:54:09 deck volumio[7853]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.812&uuid=10229ecf152dd49a0ac4761f5b04b6ad" http://updates.volumio.org/downloader-v1/track-device Jun 09 00:54:09 deck volumio[7853]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 09 00:54:09 deck volumio[7853]: Dload Upload Total Spent Left Speed Jun 09 00:54:09 deck volumio[7853]: [1.6K blob data] Jun 09 00:54:09 deck volumio[7853]: retrying in 5 seconds, trial 0 Jun 09 00:54:09 deck volumio[7853]: info: Volumio Calling Home Jun 09 00:54:10 deck volumio[7853]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 00:54:10 deck volumio[7853]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 09 00:54:10 deck volumio[7853]: at doSend (dgram.js:692:16) Jun 09 00:54:10 deck volumio[7853]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 09 00:54:10 deck volumio[7853]: at afterDns (dgram.js:638:5) Jun 09 00:54:10 deck volumio[7853]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 09 00:54:10 deck volumio[7853]: errno: -101, Jun 09 00:54:10 deck volumio[7853]: code: 'ENETUNREACH', Jun 09 00:54:10 deck volumio[7853]: syscall: 'send', Jun 09 00:54:10 deck volumio[7853]: address: '255.255.255.255', Jun 09 00:54:10 deck volumio[7853]: port: 3483 Jun 09 00:54:10 deck volumio[7853]: } Jun 09 00:54:10 deck volumio[7853]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 00:54:10 deck dhcpcd[884]: wlan0: leased 10.0.1.15 for 7200 seconds Jun 09 00:54:10 deck avahi-daemon[574]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.0.1.15. Jun 09 00:54:10 deck avahi-daemon[574]: New relevant interface wlan0.IPv4 for mDNS. Jun 09 00:54:10 deck avahi-daemon[574]: Registering new address record for 10.0.1.15 on wlan0.IPv4. Jun 09 00:54:10 deck dhcpcd[884]: wlan0: adding route to 10.0.0.0/16 Jun 09 00:54:10 deck dhcpcd[884]: wlan0: adding default route via 10.0.0.138 Jun 09 00:54:11 deck sudo[8342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-09 00:53 Jun 09 00:54:11 deck sudo[8342]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"