May 07 19:07:00 dell-3189 volumio[1377]: info: Loading plugin "randomizer"... May 07 19:07:00 dell-3189 volumio[1377]: info: Applying required configuration parameters for plugin randomizer May 07 19:07:00 dell-3189 volumio[1377]: info: Loading i18n strings for locale en May 07 19:07:00 dell-3189 volumio[1377]: Updating browse sources language May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::initPlayerControls May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:07:00 dell-3189 volumio[1377]: Express server listening on port 3000 May 07 19:07:00 dell-3189 volumio[1377]: [Metrics] WebUI: 6s 608.55ms May 07 19:07:00 dell-3189 volumio[1377]: info: Setting Device type: x86 May 07 19:07:00 dell-3189 volumio[1377]: info: CoreStateMachine::resetVolumioState May 07 19:07:00 dell-3189 volumio[1377]: info: CoreStateMachine::getcurrentVolume May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::volumioRetrievevolume May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:07:00 dell-3189 volumio[1377]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 07 19:07:00 dell-3189 volumio[1377]: info: Completed loading Core Plugins May 07 19:07:00 dell-3189 volumio[1377]: info: Preparing to generate the ALSA configuration file May 07 19:07:00 dell-3189 volumio[1377]: info: Volumio Network Manager: Network status updated: 0 May 07 19:07:00 dell-3189 volumio[1377]: info: Cannot mount NAS M1S at system boot, trial number 1 ,retrying in 5 seconds May 07 19:07:00 dell-3189 volumio[1377]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 07 19:07:00 dell-3189 volumio[1377]: info: VolumeController:: Volume=30 Mute =false May 07 19:07:00 dell-3189 volumio[1377]: info: CoreStateMachine::pushState May 07 19:07:00 dell-3189 volumio[1377]: info: CorePlayQueue::getTrack 0 May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::volumioPushState May 07 19:07:00 dell-3189 volumio[1377]: info: CoreStateMachine::updateTrackBlock May 07 19:07:00 dell-3189 volumio[1377]: info: CorePlayQueue::getTrackBlock May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::volumioRetrievevolume May 07 19:07:00 dell-3189 volumio[1377]: info: Reloading queue from file May 07 19:07:00 dell-3189 volumio[1377]: info: Asound.conf file unchanged, so no further update is needed May 07 19:07:00 dell-3189 volumio[1377]: info: Output device has changed, restarting MPD May 07 19:07:00 dell-3189 volumio[1377]: info: ___________ START PLUGINS ___________ May 07 19:07:00 dell-3189 sudo[1809]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 07 19:07:00 dell-3189 sudo[1809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:07:00 dell-3189 volumio[1377]: info: ControllerMpd::onStart: Initializing MPD May 07 19:07:00 dell-3189 volumio[1377]: info: Creating MPD Configuration file May 07 19:07:00 dell-3189 sudo[1809]: pam_unix(sudo:session): session closed for user root May 07 19:07:00 dell-3189 sudo[1811]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 07 19:07:00 dell-3189 sudo[1811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 07 19:07:00 dell-3189 volumio[1377]: info: [1746637620779] CoreMusicLibrary::Adding element Last_100 May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 07 19:07:00 dell-3189 volumio[1377]: info: [1746637620781] CoreMusicLibrary::Adding element Webradio May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 19:07:00 dell-3189 volumio[1377]: info: Initializing BBC Radios May 07 19:07:00 dell-3189 sudo[1816]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 07 19:07:00 dell-3189 sudo[1816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:07:00 dell-3189 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 07 19:07:00 dell-3189 sudo[1817]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 07 19:07:00 dell-3189 sudo[1817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:07:00 dell-3189 sudo[1816]: pam_unix(sudo:session): session closed for user root May 07 19:07:00 dell-3189 volumio[1377]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:07:00 dell-3189 systemd[1]: Starting mpd.service - Music Player Daemon... May 07 19:07:00 dell-3189 volumio[1377]: info: Creating Spotify config file May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:00 dell-3189 systemd[1]: mpd.service: Deactivated successfully. May 07 19:07:00 dell-3189 systemd[1]: Stopped mpd.service - Music Player Daemon. May 07 19:07:00 dell-3189 systemd[1]: mpd.socket: Deactivated successfully. May 07 19:07:00 dell-3189 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 07 19:07:00 dell-3189 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 07 19:07:00 dell-3189 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 07 19:07:00 dell-3189 systemd[1]: Starting mpd.service - Music Player Daemon... May 07 19:07:00 dell-3189 sudo[1834]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 07 19:07:00 dell-3189 sudo[1834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 07 19:07:00 dell-3189 sudo[1835]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 07 19:07:00 dell-3189 sudo[1834]: pam_unix(sudo:session): session closed for user root May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 07 19:07:00 dell-3189 volumio[1377]: info: [1746637620929] CoreMusicLibrary::Adding element 80s80s Radio May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 19:07:00 dell-3189 volumio[1377]: Cannot find translation for source 80s80s Radio May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 07 19:07:00 dell-3189 volumio[1377]: info: [1746637620933] CoreMusicLibrary::Adding element Radio Paradise May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 19:07:00 dell-3189 volumio[1377]: Cannot find translation for source 80s80s Radio May 07 19:07:00 dell-3189 volumio[1377]: Cannot find translation for source Radio Paradise May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 07 19:07:00 dell-3189 volumio[1377]: info: [1746637620948] CoreMusicLibrary::Adding element Randomizer May 07 19:07:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 19:07:00 dell-3189 volumio[1377]: Cannot find translation for source 80s80s Radio May 07 19:07:00 dell-3189 volumio[1377]: Cannot find translation for source Radio Paradise May 07 19:07:00 dell-3189 volumio[1377]: Cannot find translation for source Randomizer May 07 19:07:00 dell-3189 volumio[1377]: info: Volumio Calling Home May 07 19:07:01 dell-3189 volumio[1377]: info: CoreStateMachine::setRepeat null single undefined May 07 19:07:01 dell-3189 volumio[1377]: info: CoreStateMachine::pushState May 07 19:07:01 dell-3189 volumio[1377]: info: CorePlayQueue::getTrack 0 May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::volumioPushState May 07 19:07:01 dell-3189 volumio[1377]: info: CoreStateMachine::setRandom null May 07 19:07:01 dell-3189 volumio[1377]: info: CoreStateMachine::pushState May 07 19:07:01 dell-3189 volumio[1377]: info: CorePlayQueue::getTrack 0 May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::volumioPushState May 07 19:07:01 dell-3189 volumio-remote-updater[868]: [2025-05-07 19:07:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746637619 101 May 07 19:07:01 dell-3189 volumio[1377]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 May 07 19:07:01 dell-3189 volumio[1377]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io May 07 19:07:01 dell-3189 volumio[1377]: info: Discovery: adding e853a4da-5445-4843-8188-13673da2289a May 07 19:07:01 dell-3189 volumio[1377]: info: Discovery: Found device Dell-3189 May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::volumioGetState May 07 19:07:01 dell-3189 volumio[1377]: info: CorePlayQueue::getTrack 0 May 07 19:07:01 dell-3189 volumio[1377]: info: MPD Permissions set May 07 19:07:01 dell-3189 volumio[1377]: info: MPD Permissions set May 07 19:07:01 dell-3189 volumio[1377]: info: VolumeController:: Volume=30 Mute =false May 07 19:07:01 dell-3189 volumio[1377]: info: CoreStateMachine::pushState May 07 19:07:01 dell-3189 volumio[1377]: info: CorePlayQueue::getTrack 0 May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::volumioPushState May 07 19:07:01 dell-3189 volumio[1377]: info: Spotify config file written May 07 19:07:01 dell-3189 volumio[1377]: info: No need to fix Spotify hosts May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:01 dell-3189 sudo[1842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 07 19:07:01 dell-3189 sudo[1842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:07:01 dell-3189 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 07 19:07:01 dell-3189 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:01 dell-3189 go-librespot[1853]: go-librespot daemon starting... May 07 19:07:01 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:01 dell-3189 sudo[1842]: pam_unix(sudo:session): session closed for user root May 07 19:07:01 dell-3189 volumio[1377]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 07 19:07:01 dell-3189 volumio[1377]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 07 19:07:01 dell-3189 go-librespot[1861]: time="2025-05-07T19:07:01+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:07:01 dell-3189 go-librespot[1861]: time="2025-05-07T19:07:01+02:00" level=debug msg="app state loaded" May 07 19:07:01 dell-3189 go-librespot[1861]: time="2025-05-07T19:07:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:07:01 dell-3189 go-librespot[1861]: time="2025-05-07T19:07:01+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:07:01 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:07:01 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:07:01 dell-3189 volumio[1377]: info: CoreCommandRouter::volumioGetState May 07 19:07:01 dell-3189 volumio[1377]: info: CorePlayQueue::getTrack 0 May 07 19:07:02 dell-3189 volumio[1377]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 07 19:07:02 dell-3189 mpd[1836]: 2025-05-07T19:07:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 07 19:07:02 dell-3189 systemd[1]: Started mpd.service - Music Player Daemon. May 07 19:07:02 dell-3189 sudo[1811]: pam_unix(sudo:session): session closed for user root May 07 19:07:02 dell-3189 sudo[1817]: pam_unix(sudo:session): session closed for user root May 07 19:07:02 dell-3189 volumio[1377]: info: Completed starting Core Plugins May 07 19:07:02 dell-3189 volumio[1377]: info: ------------------------------------------- May 07 19:07:02 dell-3189 volumio[1377]: info: ----- MyVolumio plugins startup ---- May 07 19:07:02 dell-3189 volumio[1377]: info: ------------------------------------------- May 07 19:07:02 dell-3189 volumio[1377]: info: [MyVolumio PluginManager] Fetching plans data.... May 07 19:07:02 dell-3189 volumio[1377]: error: MPD error: The expression evaluated to a falsy value: May 07 19:07:02 dell-3189 volumio[1377]: assert.ok(self.idling) May 07 19:07:02 dell-3189 volumio[1377]: error: The expression evaluated to a falsy value: May 07 19:07:02 dell-3189 volumio[1377]: assert.ok(self.idling) May 07 19:07:02 dell-3189 volumio[1377]: info: MPD running with PID1836 May 07 19:07:02 dell-3189 volumio[1377]: ,establishing connection May 07 19:07:02 dell-3189 volumio[1377]: error: updateQueue error: null May 07 19:07:03 dell-3189 volumio[1377]: error: updateQueue error: null May 07 19:07:03 dell-3189 volumio[1377]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 07 19:07:03 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 19:07:03 dell-3189 volumio[1377]: info: CoreCommandRouter::volumioGetVisibleSources May 07 19:07:03 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 19:07:03 dell-3189 volumio[1377]: info: CoreCommandRouter::volumioGetState May 07 19:07:03 dell-3189 volumio[1377]: info: CorePlayQueue::getTrack 0 May 07 19:07:03 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 07 19:07:03 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 07 19:07:03 dell-3189 volumio[1377]: info: Received Get System Info May 07 19:07:03 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 19:07:03 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 19:07:03 dell-3189 volumio[1377]: info: Discovery: Getting this device information May 07 19:07:03 dell-3189 volumio[1377]: info: CoreCommandRouter::volumioGetState May 07 19:07:03 dell-3189 volumio[1377]: info: CorePlayQueue::getTrack 0 May 07 19:07:03 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 19:07:03 dell-3189 volumio[1377]: info: CoreCommandRouter::volumioGetState May 07 19:07:03 dell-3189 volumio[1377]: info: CorePlayQueue::getTrack 0 May 07 19:07:03 dell-3189 volumio[1377]: info: Listing playlists May 07 19:07:03 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 07 19:07:03 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 19:07:04 dell-3189 volumio[1377]: info: CoreCommandRouter::volumioGetState May 07 19:07:04 dell-3189 volumio[1377]: info: CorePlayQueue::getTrack 0 May 07 19:07:04 dell-3189 volumio[1377]: info: go-librespot daemon successfully initialized May 07 19:07:04 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. May 07 19:07:04 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:04 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:04 dell-3189 go-librespot[1873]: go-librespot daemon starting... May 07 19:07:04 dell-3189 go-librespot[1874]: time="2025-05-07T19:07:04+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:07:04 dell-3189 go-librespot[1874]: time="2025-05-07T19:07:04+02:00" level=debug msg="app state loaded" May 07 19:07:04 dell-3189 go-librespot[1874]: time="2025-05-07T19:07:04+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:07:04 dell-3189 go-librespot[1874]: time="2025-05-07T19:07:04+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:07:04 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:07:04 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:07:04 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 19:07:04 dell-3189 volumio[1377]: info: Received Get System Info May 07 19:07:04 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 19:07:04 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 19:07:04 dell-3189 volumio[1377]: info: Discovery: Getting this device information May 07 19:07:04 dell-3189 volumio[1377]: info: CoreCommandRouter::volumioGetState May 07 19:07:04 dell-3189 volumio[1377]: info: CorePlayQueue::getTrack 0 May 07 19:07:04 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 19:07:05 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 07 19:07:05 dell-3189 sudo[1881]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=odroid,password=odroid,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.15/devmon\\Music\\Music /mnt/NAS/M1S May 07 19:07:05 dell-3189 sudo[1881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:07:05 dell-3189 sudo[1881]: pam_unix(sudo:session): session closed for user root May 07 19:07:05 dell-3189 kernel: CIFS: Attempting to mount //192.168.1.15/devmon/Music/Music May 07 19:07:05 dell-3189 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 07 19:07:05 dell-3189 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 May 07 19:07:05 dell-3189 volumio[1377]: info: Cannot mount NAS M1S at system boot, trial number 2 ,retrying in 5 seconds May 07 19:07:06 dell-3189 sudo[1900]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 07 19:07:06 dell-3189 sudo[1900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:07:06 dell-3189 sudo[1902]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 07 19:07:06 dell-3189 sudo[1902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:07:06 dell-3189 sudo[1902]: pam_unix(sudo:session): session closed for user root May 07 19:07:06 dell-3189 sudo[1900]: pam_unix(sudo:session): session closed for user root May 07 19:07:06 dell-3189 sudo[1908]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 07 19:07:06 dell-3189 sudo[1908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:07:06 dell-3189 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 07 19:07:07 dell-3189 sudo[1908]: pam_unix(sudo:session): session closed for user root May 07 19:07:07 dell-3189 volumio[1377]: info: Upmpdcli Daemon Started May 07 19:07:07 dell-3189 volumio[1377]: info: Initializing connection to go-librespot Websocket May 07 19:07:07 dell-3189 volumio[1377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 07 19:07:07 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. May 07 19:07:07 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:07 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:07 dell-3189 go-librespot[1918]: go-librespot daemon starting... May 07 19:07:07 dell-3189 go-librespot[1919]: time="2025-05-07T19:07:07+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:07:07 dell-3189 go-librespot[1919]: time="2025-05-07T19:07:07+02:00" level=debug msg="app state loaded" May 07 19:07:07 dell-3189 go-librespot[1919]: time="2025-05-07T19:07:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:07:07 dell-3189 go-librespot[1919]: time="2025-05-07T19:07:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:07:07 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:07:07 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:07:08 dell-3189 volumio[1377]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 07 19:07:10 dell-3189 volumio[1377]: info: Initializing connection to go-librespot Websocket May 07 19:07:10 dell-3189 volumio[1377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 07 19:07:10 dell-3189 sudo[1926]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=odroid,password=odroid,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.15/devmon\\Music\\Music /mnt/NAS/M1S May 07 19:07:10 dell-3189 sudo[1926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:07:10 dell-3189 sudo[1926]: pam_unix(sudo:session): session closed for user root May 07 19:07:10 dell-3189 kernel: CIFS: Attempting to mount //192.168.1.15/devmon/Music/Music May 07 19:07:10 dell-3189 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 07 19:07:10 dell-3189 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 May 07 19:07:10 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. May 07 19:07:10 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:10 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:10 dell-3189 go-librespot[1930]: go-librespot daemon starting... May 07 19:07:10 dell-3189 go-librespot[1931]: time="2025-05-07T19:07:10+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:07:10 dell-3189 go-librespot[1931]: time="2025-05-07T19:07:10+02:00" level=debug msg="app state loaded" May 07 19:07:10 dell-3189 go-librespot[1931]: time="2025-05-07T19:07:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:07:10 dell-3189 go-librespot[1931]: time="2025-05-07T19:07:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:07:10 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:07:10 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:07:11 dell-3189 volumio[1377]: info: Cannot mount NAS M1S at system boot, trial number 3 ,retrying in 5 seconds May 07 19:07:11 dell-3189 volumio[1377]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=0.060&uuid=3561c36614b34649f7201e9108e2103b" http://updates.volumio.org/downloader-v1/track-device May 07 19:07:11 dell-3189 volumio[1377]: % Total % Received % Xferd Average Speed Time Time Time Current May 07 19:07:11 dell-3189 volumio[1377]: Dload Upload Total Spent Left Speed May 07 19:07:11 dell-3189 volumio[1377]: [158B blob data] May 07 19:07:11 dell-3189 volumio[1377]: curl: (7) Failed to connect to updates.volumio.org port 80 after 0 ms: Couldn't connect to server May 07 19:07:11 dell-3189 volumio[1377]: retrying in 5 seconds, trial 0 May 07 19:07:11 dell-3189 volumio[1377]: info: Volumio Calling Home May 07 19:07:13 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 07 19:07:13 dell-3189 volumio[1377]: info: Initializing connection to go-librespot Websocket May 07 19:07:14 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. May 07 19:07:14 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:14 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:14 dell-3189 go-librespot[1940]: go-librespot daemon starting... May 07 19:07:14 dell-3189 go-librespot[1941]: time="2025-05-07T19:07:14+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:07:14 dell-3189 go-librespot[1941]: time="2025-05-07T19:07:14+02:00" level=debug msg="app state loaded" May 07 19:07:14 dell-3189 go-librespot[1941]: time="2025-05-07T19:07:14+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:07:14 dell-3189 go-librespot[1941]: time="2025-05-07T19:07:14+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:07:14 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:07:14 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:07:14 dell-3189 volumio[1377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 07 19:07:16 dell-3189 sudo[1962]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=odroid,password=odroid,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.15/devmon\\Music\\Music /mnt/NAS/M1S May 07 19:07:16 dell-3189 sudo[1962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:07:16 dell-3189 kernel: CIFS: Attempting to mount //192.168.1.15/devmon/Music/Music May 07 19:07:16 dell-3189 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 07 19:07:16 dell-3189 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 May 07 19:07:16 dell-3189 sudo[1962]: pam_unix(sudo:session): session closed for user root May 07 19:07:17 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. May 07 19:07:17 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:17 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:17 dell-3189 go-librespot[1966]: go-librespot daemon starting... May 07 19:07:17 dell-3189 go-librespot[1967]: time="2025-05-07T19:07:17+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:07:17 dell-3189 go-librespot[1967]: time="2025-05-07T19:07:17+02:00" level=debug msg="app state loaded" May 07 19:07:17 dell-3189 go-librespot[1967]: time="2025-05-07T19:07:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:07:17 dell-3189 go-librespot[1967]: time="2025-05-07T19:07:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:07:17 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:07:17 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:07:17 dell-3189 volumio[1377]: info: Cannot mount NAS M1S at system boot, trial number 4 ,retrying in 5 seconds May 07 19:07:17 dell-3189 volumio[1377]: info: Initializing connection to go-librespot Websocket May 07 19:07:19 dell-3189 volumio[1377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 07 19:07:20 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. May 07 19:07:20 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:20 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:20 dell-3189 go-librespot[1973]: go-librespot daemon starting... May 07 19:07:20 dell-3189 go-librespot[1974]: time="2025-05-07T19:07:20+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:07:20 dell-3189 go-librespot[1974]: time="2025-05-07T19:07:20+02:00" level=debug msg="app state loaded" May 07 19:07:20 dell-3189 go-librespot[1974]: time="2025-05-07T19:07:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:07:20 dell-3189 go-librespot[1974]: time="2025-05-07T19:07:20+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:07:20 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:07:20 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:07:22 dell-3189 volumio[1377]: info: Initializing connection to go-librespot Websocket May 07 19:07:23 dell-3189 volumio[1377]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=0.060&uuid=3561c36614b34649f7201e9108e2103b" http://updates.volumio.org/downloader-v1/track-device May 07 19:07:23 dell-3189 volumio[1377]: % Total % Received % Xferd Average Speed Time Time Time Current May 07 19:07:23 dell-3189 volumio[1377]: Dload Upload Total Spent Left Speed May 07 19:07:23 dell-3189 volumio[1377]: [158B blob data] May 07 19:07:23 dell-3189 volumio[1377]: curl: (7) Failed to connect to updates.volumio.org port 80 after 0 ms: Couldn't connect to server May 07 19:07:23 dell-3189 volumio[1377]: retrying in 5 seconds, trial 1 May 07 19:07:23 dell-3189 volumio[1377]: info: Volumio Calling Home May 07 19:07:23 dell-3189 volumio[1377]: error: MyVolumio Plugin failed to start in a timely fashion May 07 19:07:23 dell-3189 volumio[1377]: [Metrics] CommandRouter: 28s 674.42ms May 07 19:07:23 dell-3189 volumio[1377]: info: CoreCommandRouter::volumiosetStartupVolume May 07 19:07:23 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:07:23 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:07:23 dell-3189 volumio[1377]: info: CoreCommandRouter::Close All Modals sent May 07 19:07:23 dell-3189 volumio[1377]: info: CoreCommandRouter::Close All Modals sent May 07 19:07:23 dell-3189 sudo[1983]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=odroid,password=odroid,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.15/devmon\\Music\\Music /mnt/NAS/M1S May 07 19:07:23 dell-3189 sudo[1983]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:07:23 dell-3189 sudo[1983]: pam_unix(sudo:session): session closed for user root May 07 19:07:23 dell-3189 kernel: CIFS: Attempting to mount //192.168.1.15/devmon/Music/Music May 07 19:07:23 dell-3189 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 07 19:07:23 dell-3189 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 May 07 19:07:23 dell-3189 volumio[1377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 07 19:07:23 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. May 07 19:07:23 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:23 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:23 dell-3189 go-librespot[1990]: go-librespot daemon starting... May 07 19:07:23 dell-3189 go-librespot[1991]: time="2025-05-07T19:07:23+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:07:23 dell-3189 go-librespot[1991]: time="2025-05-07T19:07:23+02:00" level=debug msg="app state loaded" May 07 19:07:23 dell-3189 go-librespot[1991]: time="2025-05-07T19:07:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:07:23 dell-3189 go-librespot[1991]: time="2025-05-07T19:07:23+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:07:23 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:07:23 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:07:24 dell-3189 volumio[1377]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 07 19:07:24 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 07 19:07:24 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 07 19:07:24 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 07 19:07:26 dell-3189 volumio[1377]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 19:07:26 dell-3189 volumio[1377]: info: Initializing connection to go-librespot Websocket May 07 19:07:27 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. May 07 19:07:27 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:27 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:27 dell-3189 go-librespot[2014]: go-librespot daemon starting... May 07 19:07:27 dell-3189 go-librespot[2015]: time="2025-05-07T19:07:27+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:07:27 dell-3189 go-librespot[2015]: time="2025-05-07T19:07:27+02:00" level=debug msg="app state loaded" May 07 19:07:27 dell-3189 go-librespot[2015]: time="2025-05-07T19:07:27+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:07:27 dell-3189 go-librespot[2015]: time="2025-05-07T19:07:27+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:07:27 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:07:27 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:07:28 dell-3189 volumio[1377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 07 19:07:29 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 07 19:07:30 dell-3189 volumio[1377]: info: BOOT COMPLETED May 07 19:07:30 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. May 07 19:07:30 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:30 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:30 dell-3189 go-librespot[2025]: go-librespot daemon starting... May 07 19:07:30 dell-3189 go-librespot[2026]: time="2025-05-07T19:07:30+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:07:30 dell-3189 go-librespot[2026]: time="2025-05-07T19:07:30+02:00" level=debug msg="app state loaded" May 07 19:07:30 dell-3189 go-librespot[2026]: time="2025-05-07T19:07:30+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:07:30 dell-3189 go-librespot[2026]: time="2025-05-07T19:07:30+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:07:30 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:07:30 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:07:31 dell-3189 volumio[1377]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory May 07 19:07:31 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:07:31 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 19:07:31 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:07:31 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 19:07:33 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. May 07 19:07:33 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:33 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:33 dell-3189 go-librespot[2035]: go-librespot daemon starting... May 07 19:07:33 dell-3189 go-librespot[2036]: time="2025-05-07T19:07:33+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:07:33 dell-3189 go-librespot[2036]: time="2025-05-07T19:07:33+02:00" level=debug msg="app state loaded" May 07 19:07:33 dell-3189 go-librespot[2036]: time="2025-05-07T19:07:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:07:33 dell-3189 go-librespot[2036]: time="2025-05-07T19:07:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:07:33 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:07:33 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:07:34 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 07 19:07:34 dell-3189 sudo[2043]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 07 19:07:34 dell-3189 sudo[2043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:07:34 dell-3189 sudo[2048]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 07 19:07:34 dell-3189 sudo[2048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:07:34 dell-3189 sudo[2048]: pam_unix(sudo:session): session closed for user root May 07 19:07:34 dell-3189 sudo[2054]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 07 19:07:34 dell-3189 sudo[2054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:07:34 dell-3189 sudo[2054]: pam_unix(sudo:session): session closed for user root May 07 19:07:34 dell-3189 sudo[2043]: pam_unix(sudo:session): session closed for user root May 07 19:07:34 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 07 19:07:34 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 07 19:07:34 dell-3189 sudo[2060]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 07 19:07:34 dell-3189 sudo[2060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:07:34 dell-3189 sudo[2060]: pam_unix(sudo:session): session closed for user root May 07 19:07:34 dell-3189 volumio[1377]: info: Initializing connection to go-librespot Websocket May 07 19:07:34 dell-3189 sudo[2069]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 07 19:07:34 dell-3189 sudo[2072]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 07 19:07:34 dell-3189 sudo[2066]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 07 19:07:34 dell-3189 sudo[2072]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:07:34 dell-3189 sudo[2066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:07:34 dell-3189 sudo[2069]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:07:34 dell-3189 sudo[2069]: pam_unix(sudo:session): session closed for user root May 07 19:07:34 dell-3189 volumio[1377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 07 19:07:34 dell-3189 sudo[2066]: pam_unix(sudo:session): session closed for user root May 07 19:07:35 dell-3189 volumio[1377]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=0.060&uuid=3561c36614b34649f7201e9108e2103b" http://updates.volumio.org/downloader-v1/track-device May 07 19:07:35 dell-3189 volumio[1377]: % Total % Received % Xferd Average Speed Time Time Time Current May 07 19:07:35 dell-3189 volumio[1377]: Dload Upload Total Spent Left Speed May 07 19:07:35 dell-3189 volumio[1377]: [158B blob data] May 07 19:07:35 dell-3189 volumio[1377]: curl: (7) Failed to connect to updates.volumio.org port 80 after 1 ms: Couldn't connect to server May 07 19:07:35 dell-3189 volumio[1377]: retrying in 5 seconds, trial 2 May 07 19:07:35 dell-3189 volumio[1377]: info: Volumio Calling Home May 07 19:07:36 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. May 07 19:07:36 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:36 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:36 dell-3189 go-librespot[2096]: go-librespot daemon starting... May 07 19:07:36 dell-3189 go-librespot[2097]: time="2025-05-07T19:07:36+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:07:36 dell-3189 go-librespot[2097]: time="2025-05-07T19:07:36+02:00" level=debug msg="app state loaded" May 07 19:07:36 dell-3189 go-librespot[2097]: time="2025-05-07T19:07:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:07:36 dell-3189 go-librespot[2097]: time="2025-05-07T19:07:36+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:07:36 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:07:36 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:07:37 dell-3189 volumio[1377]: info: Initializing connection to go-librespot Websocket May 07 19:07:37 dell-3189 volumio[1377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 07 19:07:38 dell-3189 sudo[2072]: pam_unix(sudo:session): session closed for user root May 07 19:07:40 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. May 07 19:07:40 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:40 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:40 dell-3189 go-librespot[2104]: go-librespot daemon starting... May 07 19:07:40 dell-3189 go-librespot[2105]: time="2025-05-07T19:07:40+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:07:40 dell-3189 go-librespot[2105]: time="2025-05-07T19:07:40+02:00" level=debug msg="app state loaded" May 07 19:07:40 dell-3189 go-librespot[2105]: time="2025-05-07T19:07:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:07:40 dell-3189 go-librespot[2105]: time="2025-05-07T19:07:40+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:07:40 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:07:40 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:07:40 dell-3189 volumio[1377]: info: Initializing connection to go-librespot Websocket May 07 19:07:40 dell-3189 volumio[1377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 07 19:07:43 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. May 07 19:07:43 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:43 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:43 dell-3189 go-librespot[2115]: go-librespot daemon starting... May 07 19:07:43 dell-3189 go-librespot[2116]: time="2025-05-07T19:07:43+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:07:43 dell-3189 go-librespot[2116]: time="2025-05-07T19:07:43+02:00" level=debug msg="app state loaded" May 07 19:07:43 dell-3189 go-librespot[2116]: time="2025-05-07T19:07:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:07:43 dell-3189 go-librespot[2116]: time="2025-05-07T19:07:43+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:07:43 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:07:43 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:07:43 dell-3189 volumio[1377]: info: Initializing connection to go-librespot Websocket May 07 19:07:43 dell-3189 volumio[1377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 07 19:07:46 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. May 07 19:07:46 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:46 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:46 dell-3189 go-librespot[2122]: go-librespot daemon starting... May 07 19:07:46 dell-3189 go-librespot[2123]: time="2025-05-07T19:07:46+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:07:46 dell-3189 go-librespot[2123]: time="2025-05-07T19:07:46+02:00" level=debug msg="app state loaded" May 07 19:07:46 dell-3189 go-librespot[2123]: time="2025-05-07T19:07:46+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:07:46 dell-3189 go-librespot[2123]: time="2025-05-07T19:07:46+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:07:46 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:07:46 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:07:46 dell-3189 volumio[1377]: info: Initializing connection to go-librespot Websocket May 07 19:07:46 dell-3189 volumio[1377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 07 19:07:49 dell-3189 volumio[1377]: info: Initializing connection to go-librespot Websocket May 07 19:07:49 dell-3189 volumio[1377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 07 19:07:49 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. May 07 19:07:49 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:49 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:49 dell-3189 go-librespot[2143]: go-librespot daemon starting... May 07 19:07:49 dell-3189 go-librespot[2144]: time="2025-05-07T19:07:49+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:07:49 dell-3189 go-librespot[2144]: time="2025-05-07T19:07:49+02:00" level=debug msg="app state loaded" May 07 19:07:49 dell-3189 go-librespot[2144]: time="2025-05-07T19:07:49+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:07:49 dell-3189 go-librespot[2144]: time="2025-05-07T19:07:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:07:49 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:07:49 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:07:52 dell-3189 volumio[1377]: info: Initializing connection to go-librespot Websocket May 07 19:07:52 dell-3189 volumio[1377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 07 19:07:53 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. May 07 19:07:53 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:53 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:53 dell-3189 go-librespot[2150]: go-librespot daemon starting... May 07 19:07:53 dell-3189 go-librespot[2151]: time="2025-05-07T19:07:53+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:07:53 dell-3189 go-librespot[2151]: time="2025-05-07T19:07:53+02:00" level=debug msg="app state loaded" May 07 19:07:53 dell-3189 go-librespot[2151]: time="2025-05-07T19:07:53+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:07:53 dell-3189 go-librespot[2151]: time="2025-05-07T19:07:53+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:07:53 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:07:53 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:07:55 dell-3189 volumio[1377]: info: Initializing connection to go-librespot Websocket May 07 19:07:55 dell-3189 volumio[1377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 07 19:07:56 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. May 07 19:07:56 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:56 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:56 dell-3189 go-librespot[2158]: go-librespot daemon starting... May 07 19:07:56 dell-3189 go-librespot[2159]: time="2025-05-07T19:07:56+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:07:56 dell-3189 go-librespot[2159]: time="2025-05-07T19:07:56+02:00" level=debug msg="app state loaded" May 07 19:07:56 dell-3189 go-librespot[2159]: time="2025-05-07T19:07:56+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:07:56 dell-3189 go-librespot[2159]: time="2025-05-07T19:07:56+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:07:56 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:07:56 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:07:58 dell-3189 systemd[1]: nmbd.service: start operation timed out. Terminating. May 07 19:07:58 dell-3189 systemd[1]: nmbd.service: Failed with result 'timeout'. May 07 19:07:58 dell-3189 systemd[1]: Failed to start nmbd.service - Samba NMB Daemon. May 07 19:07:58 dell-3189 systemd[1]: Starting winbind.service - Samba Winbind Daemon... May 07 19:07:58 dell-3189 winbindd[2181]: [2025/05/07 19:07:58.299605, 0] ../../source3/winbindd/winbindd.c:1440(main) May 07 19:07:58 dell-3189 winbindd[2181]: winbindd version 4.17.12-Debian started. May 07 19:07:58 dell-3189 winbindd[2181]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 07 19:07:58 dell-3189 winbindd[2181]: [2025/05/07 19:07:58.307816, 0] ../../source3/winbindd/winbindd_cache.c:3117(initialize_winbindd_cache) May 07 19:07:58 dell-3189 winbindd[2181]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 07 19:07:58 dell-3189 systemd[1]: Started winbind.service - Samba Winbind Daemon. May 07 19:07:58 dell-3189 systemd[1]: Starting smbd.service - Samba SMB Daemon... May 07 19:07:58 dell-3189 smbd[2190]: [2025/05/07 19:07:58.556781, 0] ../../source3/smbd/server.c:1741(main) May 07 19:07:58 dell-3189 smbd[2190]: smbd version 4.17.12-Debian started. May 07 19:07:58 dell-3189 smbd[2190]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 07 19:07:58 dell-3189 volumio[1377]: info: Initializing connection to go-librespot Websocket May 07 19:07:58 dell-3189 volumio[1377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 07 19:07:58 dell-3189 systemd[1]: Started smbd.service - Samba SMB Daemon. May 07 19:07:58 dell-3189 systemd[1]: Reached target multi-user.target - Multi-User System. May 07 19:07:58 dell-3189 systemd[1]: Reached target graphical.target - Graphical Interface. May 07 19:07:58 dell-3189 systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... May 07 19:07:58 dell-3189 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. May 07 19:07:58 dell-3189 systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. May 07 19:07:58 dell-3189 systemd[1]: Startup finished in 11.018s (firmware) + 3.067s (loader) + 8.747s (kernel) + 1min 34.523s (userspace) = 1min 57.356s. May 07 19:07:59 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. May 07 19:07:59 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:59 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:07:59 dell-3189 go-librespot[2207]: go-librespot daemon starting... May 07 19:07:59 dell-3189 go-librespot[2208]: time="2025-05-07T19:07:59+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:07:59 dell-3189 go-librespot[2208]: time="2025-05-07T19:07:59+02:00" level=debug msg="app state loaded" May 07 19:07:59 dell-3189 go-librespot[2208]: time="2025-05-07T19:07:59+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:07:59 dell-3189 go-librespot[2208]: time="2025-05-07T19:07:59+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:07:59 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:07:59 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:08:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings May 07 19:08:00 dell-3189 volumio[1377]: info: Saving new wireless network May 07 19:08:00 dell-3189 sudo[2215]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf May 07 19:08:00 dell-3189 sudo[2215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:08:00 dell-3189 sudo[2215]: pam_unix(sudo:session): session closed for user root May 07 19:08:00 dell-3189 volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 07 19:08:00 dell-3189 volumio[1377]: info: Discovery: Restarting Advertising due to device name change May 07 19:08:00 dell-3189 sudo[2218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 07 19:08:00 dell-3189 sudo[2218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:08:00 dell-3189 wpa_supplicant[1293]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all May 07 19:08:00 dell-3189 wpa_supplicant[1293]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all May 07 19:08:00 dell-3189 wpa_supplicant[1293]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 May 07 19:08:00 dell-3189 systemd[1]: Stopping wireless.service - Wireless Services... May 07 19:08:00 dell-3189 wpa_supplicant[1293]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING May 07 19:08:00 dell-3189 wpa_supplicant[1293]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all May 07 19:08:00 dell-3189 wpa_supplicant[1293]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all May 07 19:08:00 dell-3189 wpa_supplicant[1293]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 May 07 19:08:00 dell-3189 wpa_supplicant[1293]: wlan0: CTRL-EVENT-TERMINATING May 07 19:08:00 dell-3189 systemd[1]: wireless.service: Deactivated successfully. May 07 19:08:00 dell-3189 systemd[1]: Stopped wireless.service - Wireless Services. May 07 19:08:00 dell-3189 systemd[1]: wireless.service: Consumed 1.855s CPU time. May 07 19:08:00 dell-3189 systemd[1]: Starting wireless.service - Wireless Services... May 07 19:08:01 dell-3189 wireless.js[2220]: Cleaning previous... May 07 19:08:01 dell-3189 sudo[2238]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 May 07 19:08:01 dell-3189 sudo[2238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 07 19:08:01 dell-3189 sudo[2238]: pam_unix(sudo:session): session closed for user root May 07 19:08:01 dell-3189 sudo[2240]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down May 07 19:08:01 dell-3189 sudo[2240]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 07 19:08:01 dell-3189 sudo[2240]: pam_unix(sudo:session): session closed for user root May 07 19:08:01 dell-3189 wireless.js[2220]: Stopped aP May 07 19:08:01 dell-3189 sudo[2248]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 07 19:08:01 dell-3189 sudo[2248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:08:01 dell-3189 kernel: iwlwifi 0000:01:00.0: Applying debug destination EXTERNAL_DRAM May 07 19:08:01 dell-3189 kernel: iwlwifi 0000:01:00.0: Applying debug destination EXTERNAL_DRAM May 07 19:08:01 dell-3189 kernel: iwlwifi 0000:01:00.0: FW already configured (0) - re-configuring May 07 19:08:01 dell-3189 sudo[2248]: pam_unix(sudo:session): session closed for user root May 07 19:08:01 dell-3189 sudo[2250]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 07 19:08:01 dell-3189 sudo[2250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:08:01 dell-3189 sudo[2250]: pam_unix(sudo:session): session closed for user root May 07 19:08:01 dell-3189 sudo[2258]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 07 19:08:01 dell-3189 sudo[2258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:08:01 dell-3189 sudo[2258]: pam_unix(sudo:session): session closed for user root May 07 19:08:01 dell-3189 sudo[2260]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 07 19:08:01 dell-3189 sudo[2260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:08:01 dell-3189 volumio[1377]: info: Initializing connection to go-librespot Websocket May 07 19:08:01 dell-3189 volumio[1377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 07 19:08:01 dell-3189 volumio[1377]: info: Discovery: A device disappeared from network May 07 19:08:01 dell-3189 volumio[1377]: info: Discovery: Device dell-3189 disappeared from network May 07 19:08:02 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. May 07 19:08:02 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:08:02 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:08:02 dell-3189 go-librespot[2264]: go-librespot daemon starting... May 07 19:08:02 dell-3189 go-librespot[2265]: time="2025-05-07T19:08:02+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:08:02 dell-3189 go-librespot[2265]: time="2025-05-07T19:08:02+02:00" level=debug msg="app state loaded" May 07 19:08:02 dell-3189 go-librespot[2265]: time="2025-05-07T19:08:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:08:02 dell-3189 go-librespot[2265]: time="2025-05-07T19:08:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:08:02 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:08:02 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:08:04 dell-3189 sudo[2260]: pam_unix(sudo:session): session closed for user root May 07 19:08:04 dell-3189 wireless.js[2220]: SETTING APPROPRIATE REG DOMAIN: NL May 07 19:08:04 dell-3189 sudo[2273]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 07 19:08:04 dell-3189 sudo[2273]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:08:04 dell-3189 sudo[2273]: pam_unix(sudo:session): session closed for user root May 07 19:08:04 dell-3189 sudo[2275]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set NL May 07 19:08:04 dell-3189 sudo[2275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:08:04 dell-3189 sudo[2275]: pam_unix(sudo:session): session closed for user root May 07 19:08:04 dell-3189 wireless.js[2220]: SUCCESSFULLY SET NEW REGDOMAIN: NL May 07 19:08:04 dell-3189 wireless.js[2220]: Start wireless flow May 07 19:08:04 dell-3189 wireless.js[2220]: Stopped hotspot (if there).. May 07 19:08:04 dell-3189 sudo[2280]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 May 07 19:08:04 dell-3189 sudo[2280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 07 19:08:04 dell-3189 sudo[2280]: pam_unix(sudo:session): session closed for user root May 07 19:08:04 dell-3189 sudo[2282]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down May 07 19:08:04 dell-3189 sudo[2282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 07 19:08:04 dell-3189 sudo[2282]: pam_unix(sudo:session): session closed for user root May 07 19:08:04 dell-3189 wireless.js[2220]: DHCP IP May 07 19:08:04 dell-3189 wpa_supplicant[2284]: Successfully initialized wpa_supplicant May 07 19:08:04 dell-3189 wireless.js[2220]: Start ap May 07 19:08:04 dell-3189 sudo[2285]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 07 19:08:04 dell-3189 sudo[2285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 07 19:08:04 dell-3189 dhcpcd[935]: ps_ctl_dispatch: cannot handle another client May 07 19:08:04 dell-3189 dhcpcd[935]: control_free: No such file or directory May 07 19:08:04 dell-3189 sudo[2285]: pam_unix(sudo:session): session closed for user root May 07 19:08:04 dell-3189 kernel: iwlwifi 0000:01:00.0: Applying debug destination EXTERNAL_DRAM May 07 19:08:04 dell-3189 kernel: iwlwifi 0000:01:00.0: Applying debug destination EXTERNAL_DRAM May 07 19:08:04 dell-3189 kernel: iwlwifi 0000:01:00.0: FW already configured (0) - re-configuring May 07 19:08:04 dell-3189 wpa_supplicant[2289]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=WORLD May 07 19:08:04 dell-3189 volumio[1377]: info: Initializing connection to go-librespot Websocket May 07 19:08:04 dell-3189 volumio[1377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 07 19:08:05 dell-3189 wireless.js[2220]: trying... May 07 19:08:05 dell-3189 sudo[2292]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 07 19:08:05 dell-3189 sudo[2292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:08:05 dell-3189 sudo[2292]: pam_unix(sudo:session): session closed for user root May 07 19:08:05 dell-3189 volumio[1377]: info: Discovery: Started advertising with name: Dell-3189 May 07 19:08:06 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. May 07 19:08:06 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:08:06 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:08:06 dell-3189 go-librespot[2295]: go-librespot daemon starting... May 07 19:08:06 dell-3189 go-librespot[2296]: time="2025-05-07T19:08:06+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:08:06 dell-3189 go-librespot[2296]: time="2025-05-07T19:08:06+02:00" level=debug msg="app state loaded" May 07 19:08:06 dell-3189 go-librespot[2296]: time="2025-05-07T19:08:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:08:06 dell-3189 go-librespot[2296]: time="2025-05-07T19:08:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:08:06 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:08:06 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:08:06 dell-3189 wireless.js[2220]: trying... May 07 19:08:06 dell-3189 sudo[2304]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 07 19:08:06 dell-3189 sudo[2304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:08:06 dell-3189 sudo[2304]: pam_unix(sudo:session): session closed for user root May 07 19:08:06 dell-3189 volumio[1377]: info: Discovery: adding e853a4da-5445-4843-8188-13673da2289a May 07 19:08:06 dell-3189 volumio[1377]: info: Discovery: Found device Dell-3189 May 07 19:08:06 dell-3189 volumio[1377]: info: CoreCommandRouter::volumioGetState May 07 19:08:06 dell-3189 volumio[1377]: info: CorePlayQueue::getTrack 0 May 07 19:08:07 dell-3189 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 07 19:08:07 dell-3189 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 07 19:08:07 dell-3189 wireless.js[2220]: trying... May 07 19:08:07 dell-3189 sudo[2324]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 07 19:08:07 dell-3189 sudo[2324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:08:07 dell-3189 sudo[2324]: pam_unix(sudo:session): session closed for user root May 07 19:08:07 dell-3189 volumio[1377]: info: Initializing connection to go-librespot Websocket May 07 19:08:07 dell-3189 volumio[1377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 07 19:08:07 dell-3189 wpa_supplicant[2289]: wlan0: SME: Trying to authenticate with 30:23:03:43:74:c0 (SSID='WieFie_5GHz' freq=5240 MHz) May 07 19:08:07 dell-3189 kernel: wlan0: authenticate with 30:23:03:43:74:c0 May 07 19:08:07 dell-3189 wpa_supplicant[2289]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=NL May 07 19:08:07 dell-3189 wpa_supplicant[2289]: wlan0: Trying to associate with 30:23:03:43:74:c0 (SSID='WieFie_5GHz' freq=5240 MHz) May 07 19:08:07 dell-3189 kernel: wlan0: send auth to 30:23:03:43:74:c0 (try 1/3) May 07 19:08:07 dell-3189 kernel: wlan0: authenticated May 07 19:08:07 dell-3189 kernel: wlan0: associate with 30:23:03:43:74:c0 (try 1/3) May 07 19:08:07 dell-3189 kernel: wlan0: RX AssocResp from 30:23:03:43:74:c0 (capab=0x31 status=0 aid=3) May 07 19:08:07 dell-3189 wpa_supplicant[2289]: wlan0: Associated with 30:23:03:43:74:c0 May 07 19:08:07 dell-3189 wpa_supplicant[2289]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 07 19:08:07 dell-3189 kernel: wlan0: associated May 07 19:08:08 dell-3189 wpa_supplicant[2289]: wlan0: WPA: Key negotiation completed with 30:23:03:43:74:c0 [PTK=CCMP GTK=CCMP] May 07 19:08:08 dell-3189 wpa_supplicant[2289]: wlan0: CTRL-EVENT-CONNECTED - Connection to 30:23:03:43:74:c0 completed [id=0 id_str=] May 07 19:08:08 dell-3189 dhcpcd[935]: wlan0: carrier acquired May 07 19:08:08 dell-3189 dhcpcd[935]: wlan0: connected to Access Point: WieFie_5GHz May 07 19:08:08 dell-3189 dhcpcd[935]: wlan0: IAID 35:75:65:52 May 07 19:08:08 dell-3189 dhcpcd[935]: wlan0: soliciting a DHCP lease May 07 19:08:08 dell-3189 dhcpcd[935]: wlan0: offered 192.168.1.94 from 192.168.1.1 May 07 19:08:08 dell-3189 wireless.js[2220]: trying... May 07 19:08:08 dell-3189 sudo[2345]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 07 19:08:08 dell-3189 sudo[2345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:08:08 dell-3189 sudo[2345]: pam_unix(sudo:session): session closed for user root May 07 19:08:08 dell-3189 dhcpcd[935]: wlan0: probing address 192.168.1.94/24 May 07 19:08:08 dell-3189 dhcpcd[935]: wlan0: soliciting an IPv6 router May 07 19:08:09 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. May 07 19:08:09 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:08:09 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:08:09 dell-3189 go-librespot[2348]: go-librespot daemon starting... May 07 19:08:09 dell-3189 go-librespot[2349]: time="2025-05-07T19:08:09+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:08:09 dell-3189 go-librespot[2349]: time="2025-05-07T19:08:09+02:00" level=debug msg="app state loaded" May 07 19:08:09 dell-3189 go-librespot[2349]: time="2025-05-07T19:08:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:08:09 dell-3189 go-librespot[2349]: time="2025-05-07T19:08:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:08:09 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:08:09 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:08:09 dell-3189 wireless.js[2220]: trying... May 07 19:08:09 dell-3189 sudo[2357]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 07 19:08:09 dell-3189 sudo[2357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:08:09 dell-3189 sudo[2357]: pam_unix(sudo:session): session closed for user root May 07 19:08:10 dell-3189 wireless.js[2220]: trying... May 07 19:08:10 dell-3189 sudo[2361]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 07 19:08:10 dell-3189 sudo[2361]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:08:10 dell-3189 sudo[2361]: pam_unix(sudo:session): session closed for user root May 07 19:08:10 dell-3189 volumio[1377]: info: Initializing connection to go-librespot Websocket May 07 19:08:10 dell-3189 volumio[1377]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 07 19:08:11 dell-3189 wireless.js[2220]: trying... May 07 19:08:11 dell-3189 sudo[2365]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 07 19:08:11 dell-3189 sudo[2365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:08:11 dell-3189 sudo[2365]: pam_unix(sudo:session): session closed for user root May 07 19:08:12 dell-3189 wireless.js[2220]: trying... May 07 19:08:12 dell-3189 sudo[2369]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 07 19:08:12 dell-3189 sudo[2369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:08:12 dell-3189 sudo[2369]: pam_unix(sudo:session): session closed for user root May 07 19:08:12 dell-3189 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. May 07 19:08:12 dell-3189 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 07 19:08:12 dell-3189 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 07 19:08:12 dell-3189 go-librespot[2371]: go-librespot daemon starting... May 07 19:08:12 dell-3189 go-librespot[2372]: time="2025-05-07T19:08:12+02:00" level=info msg="running go-librespot 0.2.0" May 07 19:08:12 dell-3189 go-librespot[2372]: time="2025-05-07T19:08:12+02:00" level=debug msg="app state loaded" May 07 19:08:12 dell-3189 go-librespot[2372]: time="2025-05-07T19:08:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 19:08:12 dell-3189 go-librespot[2372]: time="2025-05-07T19:08:12+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 07 19:08:12 dell-3189 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 07 19:08:12 dell-3189 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 07 19:08:13 dell-3189 dhcpcd[935]: wlan0: leased 192.168.1.94 for 86400 seconds May 07 19:08:13 dell-3189 avahi-daemon[858]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.94. May 07 19:08:13 dell-3189 dhcpcd[935]: wlan0: adding route to 192.168.1.0/24 May 07 19:08:13 dell-3189 dhcpcd[935]: wlan0: adding default route via 192.168.1.1 May 07 19:08:13 dell-3189 avahi-daemon[858]: New relevant interface wlan0.IPv4 for mDNS. May 07 19:08:13 dell-3189 avahi-daemon[858]: Registering new address record for 192.168.1.94 on wlan0.IPv4. May 07 19:08:13 dell-3189 systemd[1]: welcome.service: Deactivated successfully. May 07 19:08:13 dell-3189 systemd[1]: Stopped welcome.service - Show a welcome message on console. May 07 19:08:13 dell-3189 systemd[1]: Stopping welcome.service - Show a welcome message on console... May 07 19:08:13 dell-3189 systemd[1]: Starting welcome.service - Show a welcome message on console... May 07 19:08:13 dell-3189 welcome[2389]: Resolved ip:[1] 192.168.1.94 May 07 19:08:13 dell-3189 systemd[1]: Finished welcome.service - Show a welcome message on console. May 07 19:08:13 dell-3189 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 07 19:08:13 dell-3189 volumio[1377]: compat.c: read() failed: Resource temporarily unavailable May 07 19:08:13 dell-3189 volumio[1377]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 07 19:08:13 dell-3189 volumio[1377]: Error: dns service error: unknown May 07 19:08:13 dell-3189 volumio[1377]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { May 07 19:08:13 dell-3189 volumio[1377]: errorCode: -65537 May 07 19:08:13 dell-3189 volumio[1377]: } May 07 19:08:13 dell-3189 volumio[1377]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 07 19:08:13 dell-3189 wireless.js[2220]: trying... May 07 19:08:13 dell-3189 sudo[2419]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 07 19:08:13 dell-3189 sudo[2419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:08:13 dell-3189 sudo[2419]: pam_unix(sudo:session): session closed for user root May 07 19:08:13 dell-3189 sudo[2422]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-07 19:07' May 07 19:08:13 dell-3189 sudo[2422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 07 19:08:13 dell-3189 wireless.js[2220]: Connected to: ----WieFie_5GHz May 07 19:08:13 dell-3189 wireless.js[2220]: ---- May 07 19:08:13 dell-3189 sudo[2426]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 07 19:08:13 dell-3189 sudo[2426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 07 19:08:13 dell-3189 sudo[2426]: pam_unix(sudo:session): session closed for user root May 07 19:08:13 dell-3189 wireless.js[2220]: ... joined AP, wlan0 IPv4 is 192.168.1.94, ipV6 is undefined May 07 19:08:13 dell-3189 wireless.js[2220]: It's done! AP PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5431d94b4ec10a9432d7232ad6f9406bf7be8de2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Apr 24 13:18:51 UTC 2025" VOLUMIO_VERSION="0.060" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="b52e798d3aee410c6676cfef810c42a3"